-- Logs begin at Mon 2024-03-25 22:12:52 CET, end at Mon 2024-03-25 22:13:17 CET. -- Mar 25 22:12:52 sparamuseca kernel: Booting Linux on physical CPU 0x0 Mar 25 22:12:52 sparamuseca kernel: Linux version 4.9.241+ (odroidn2@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #7 SMP PREEMPT Sat Apr 9 15:06:53 CEST 2022 Mar 25 22:12:52 sparamuseca kernel: Boot CPU: AArch64 Processor [410fd034] Mar 25 22:12:52 sparamuseca kernel: Machine model: Hardkernel ODROID-N2Plus Mar 25 22:12:52 sparamuseca kernel: efi: Getting EFI parameters from FDT: Mar 25 22:12:52 sparamuseca kernel: efi: UEFI not found. Mar 25 22:12:52 sparamuseca kernel: __reserved_mem_alloc_size, start:0x000000007f800000, end:0x0000000080000000, len:8 MiB Mar 25 22:12:52 sparamuseca kernel: 7f800000 - 80000000, 8192 KB, linux,meson-fb Mar 25 22:12:52 sparamuseca kernel: e5800000 - ed800000, 131072 KB, linux,ion-dev Mar 25 22:12:52 sparamuseca kernel: e3000000 - e5800000, 40960 KB, linux,di_cma Mar 25 22:12:52 sparamuseca kernel: Reserved memory: created DMA memory pool at 0x00000000e3000000, size 0 MiB Mar 25 22:12:52 sparamuseca kernel: e3000000 - e3000000, 0 KB, linux,ppmgr Mar 25 22:12:52 sparamuseca kernel: cfc00000 - e3000000, 315392 KB, linux,codec_mm_cma Mar 25 22:12:52 sparamuseca kernel: cfc00000 - cfc00000, 0 KB, linux,codec_mm_reserved Mar 25 22:12:52 sparamuseca kernel: __reserved_mem_alloc_size, start:0x0000000005000000, end:0x0000000005400000, len:4 MiB Mar 25 22:12:52 sparamuseca kernel: 05000000 - 05400000, 4096 KB, linux,secmon Mar 25 22:12:52 sparamuseca kernel: cma: Reserved 8 MiB at 0x00000000cf400000 Mar 25 22:12:52 sparamuseca kernel: On node 0 totalpages: 972800 Mar 25 22:12:52 sparamuseca kernel: DMA zone: 15200 pages used for memmap Mar 25 22:12:52 sparamuseca kernel: DMA zone: 0 pages reserved Mar 25 22:12:52 sparamuseca kernel: DMA zone: 972800 pages, LIFO batch:31 Mar 25 22:12:52 sparamuseca kernel: psci: probing for conduit method from DT. Mar 25 22:12:52 sparamuseca kernel: psci: PSCIv1.0 detected in firmware. Mar 25 22:12:52 sparamuseca kernel: psci: Using standard PSCI v0.2 function IDs Mar 25 22:12:52 sparamuseca kernel: psci: MIGRATE_INFO_TYPE not supported. Mar 25 22:12:52 sparamuseca kernel: psci: SMC Calling Convention v1.1 Mar 25 22:12:52 sparamuseca kernel: percpu: Embedded 21 pages/cpu s47960 r8192 d29864 u86016 Mar 25 22:12:52 sparamuseca kernel: pcpu-alloc: s47960 r8192 d29864 u86016 alloc=21*4096 Mar 25 22:12:52 sparamuseca kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 Mar 25 22:12:52 sparamuseca kernel: Detected VIPT I-cache on CPU0 Mar 25 22:12:52 sparamuseca kernel: CPU features: enabling workaround for ARM erratum 845719 Mar 25 22:12:52 sparamuseca kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 957600 Mar 25 22:12:52 sparamuseca kernel: Kernel command line: imgpart=UUID=6ef95bd7-5a8d-43cb-8c3c-60636192475e bootpart=UUID=FD06-2167 datapart=UUID=643ce33f-986f-4dfc-84f3-85a67e7d2e9d imgfile=/volumio_current.sqsh bootconfig=boot.ini loglevel=0 hwdevice=Odroid-N2 bootdelay=5 gpiopower=479 rootwait rw console=ttyS0,115200n8 no_console_suspend fsck.repair=yes net.ifnames=0 elevator=noop hdmimode=1080p60hz cvbsmode=576cvbs max_freq_a53=1908 max_freq_a73=2208 maxcpus=6 voutmode=hdmi disablehpd=false cvbscable=0 overscan=100 monitor_onoff=false logo=osd0,loaded hdmitx=cec3f sdrmode=auto Mar 25 22:12:52 sparamuseca kernel: gpio-keypad: gpiopower_setup gpiopower : 479 Mar 25 22:12:52 sparamuseca kernel: vout: get hdmimode: 1080p60hz Mar 25 22:12:52 sparamuseca kernel: vout: get cvbsmode: 576cvbs Mar 25 22:12:52 sparamuseca kernel: max cpufreq of cluster0 : 1908000kHz Mar 25 22:12:52 sparamuseca kernel: max cpufreq of cluster1 : 2208000kHz Mar 25 22:12:52 sparamuseca kernel: hdmitx: voutmode : 1 Mar 25 22:12:52 sparamuseca kernel: fb: osd0 Mar 25 22:12:52 sparamuseca kernel: fb: loaded Mar 25 22:12:52 sparamuseca kernel: hdmitx: HDMI hdmi_cec_func_config:0x3f Mar 25 22:12:52 sparamuseca kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Mar 25 22:12:52 sparamuseca kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) Mar 25 22:12:52 sparamuseca kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) Mar 25 22:12:52 sparamuseca kernel: Memory: 3280772K/3891200K available (11964K kernel code, 1816K rwdata, 5136K rodata, 4992K init, 1421K bss, 102524K reserved, 507904K cma-reserved) Mar 25 22:12:52 sparamuseca kernel: Virtual kernel memory layout: Mar 25 22:12:52 sparamuseca kernel: modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) Mar 25 22:12:52 sparamuseca kernel: vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB) Mar 25 22:12:52 sparamuseca kernel: .text : 0xffffff8009080000 - 0xffffff8009c30000 ( 11968 KB) Mar 25 22:12:52 sparamuseca kernel: .rodata : 0xffffff8009c30000 - 0xffffff800a140000 ( 5184 KB) Mar 25 22:12:52 sparamuseca kernel: .init : 0xffffff800a140000 - 0xffffff800a620000 ( 4992 KB) Mar 25 22:12:52 sparamuseca kernel: .data : 0xffffff800a620000 - 0xffffff800a7e6200 ( 1817 KB) Mar 25 22:12:52 sparamuseca kernel: .bss : 0xffffff800a7e6200 - 0xffffff800a9498bc ( 1422 KB) Mar 25 22:12:52 sparamuseca kernel: fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB) Mar 25 22:12:52 sparamuseca kernel: PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB) Mar 25 22:12:52 sparamuseca kernel: vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum) Mar 25 22:12:52 sparamuseca kernel: 0xffffffbf00000000 - 0xffffffbf03b60000 ( 59 MB actual) Mar 25 22:12:52 sparamuseca kernel: memory : 0xffffffc000000000 - 0xffffffc0ed800000 ( 3800 MB) Mar 25 22:12:52 sparamuseca kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Mar 25 22:12:52 sparamuseca kernel: Preemptible hierarchical RCU implementation. Mar 25 22:12:52 sparamuseca kernel: Build-time adjustment of leaf fanout to 64. Mar 25 22:12:52 sparamuseca kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=6. Mar 25 22:12:52 sparamuseca kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=6 Mar 25 22:12:52 sparamuseca kernel: NR_IRQS:64 nr_irqs:64 0 Mar 25 22:12:52 sparamuseca kernel: GPIO-INTC: support to detect double-edge trigger signal Mar 25 22:12:52 sparamuseca kernel: g12a_aoclkc_init: register ao clk ok! Mar 25 22:12:52 sparamuseca kernel: Meson chip version = RevC (29:C - 40:0) Mar 25 22:12:52 sparamuseca kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Mar 25 22:12:52 sparamuseca kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Mar 25 22:12:52 sparamuseca kernel: meson_g12a_gpu_init: register meson gpu clk Mar 25 22:12:52 sparamuseca kernel: meson_g12a_media_init: register meson media clk Mar 25 22:12:52 sparamuseca kernel: meson_g12a_misc_init: register amlogic g12a misc clks Mar 25 22:12:52 sparamuseca kernel: meson_g12a_misc_init: done. Mar 25 22:12:52 sparamuseca kernel: g12a_clkc_init initialization complete Mar 25 22:12:52 sparamuseca kernel: arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). Mar 25 22:12:52 sparamuseca kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Mar 25 22:12:52 sparamuseca kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Mar 25 22:12:52 sparamuseca kernel: meson_bc_timer: mclk->mux_reg =ffffff800800c190,mclk->reg =ffffff800800e194 Mar 25 22:12:52 sparamuseca kernel: Console: colour dummy device 80x25 Mar 25 22:12:52 sparamuseca kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Mar 25 22:12:52 sparamuseca kernel: pid_max: default: 32768 minimum: 301 Mar 25 22:12:52 sparamuseca kernel: Security Framework initialized Mar 25 22:12:52 sparamuseca kernel: SELinux: Initializing. Mar 25 22:12:52 sparamuseca kernel: SELinux: Starting in permissive mode Mar 25 22:12:52 sparamuseca kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) Mar 25 22:12:52 sparamuseca kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) Mar 25 22:12:52 sparamuseca kernel: ftrace: allocating 39807 entries in 156 pages Mar 25 22:12:52 sparamuseca kernel: sched-energy: Sched-energy-costs installed from DT Mar 25 22:12:52 sparamuseca kernel: CPU0: update cpu_capacity 631 Mar 25 22:12:52 sparamuseca kernel: ASID allocator initialised with 65536 entries Mar 25 22:12:52 sparamuseca kernel: secmon: clear_range:5100000 200000 Mar 25 22:12:52 sparamuseca kernel: EFI services will not be available. Mar 25 22:12:52 sparamuseca kernel: Detected VIPT I-cache on CPU1 Mar 25 22:12:52 sparamuseca kernel: CPU1: update cpu_capacity 631 Mar 25 22:12:52 sparamuseca kernel: CPU1: Booted secondary processor [410fd034] Mar 25 22:12:52 sparamuseca kernel: Detected VIPT I-cache on CPU2 Mar 25 22:12:52 sparamuseca kernel: CPU2: update cpu_capacity 1192 Mar 25 22:12:52 sparamuseca kernel: CPU2: Booted secondary processor [410fd092] Mar 25 22:12:52 sparamuseca kernel: Detected VIPT I-cache on CPU3 Mar 25 22:12:52 sparamuseca kernel: CPU3: update cpu_capacity 1192 Mar 25 22:12:52 sparamuseca kernel: CPU3: Booted secondary processor [410fd092] Mar 25 22:12:52 sparamuseca kernel: Detected VIPT I-cache on CPU4 Mar 25 22:12:52 sparamuseca kernel: CPU4: update cpu_capacity 1192 Mar 25 22:12:52 sparamuseca kernel: CPU4: Booted secondary processor [410fd092] Mar 25 22:12:52 sparamuseca kernel: Detected VIPT I-cache on CPU5 Mar 25 22:12:52 sparamuseca kernel: CPU5: update cpu_capacity 1192 Mar 25 22:12:52 sparamuseca kernel: CPU5: Booted secondary processor [410fd092] Mar 25 22:12:52 sparamuseca kernel: Brought up 6 CPUs Mar 25 22:12:52 sparamuseca kernel: SMP: Total of 6 processors activated. Mar 25 22:12:52 sparamuseca kernel: CPU features: detected feature: 32-bit EL0 Support Mar 25 22:12:52 sparamuseca kernel: CPU: All CPU(s) started at EL2 Mar 25 22:12:52 sparamuseca kernel: alternatives: patching kernel code Mar 25 22:12:52 sparamuseca kernel: devtmpfs: initialized Mar 25 22:12:52 sparamuseca kernel: DMI not present or invalid. Mar 25 22:12:52 sparamuseca kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Mar 25 22:12:52 sparamuseca kernel: futex hash table entries: 2048 (order: 5, 131072 bytes) Mar 25 22:12:52 sparamuseca kernel: pinctrl core: initialized pinctrl subsystem Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 16 Mar 25 22:12:52 sparamuseca kernel: schedtune: init normalization constants... Mar 25 22:12:52 sparamuseca kernel: schedtune: CLUSTER[0-1] min_pwr: 0 max_pwr: 42 Mar 25 22:12:52 sparamuseca kernel: schedtune: CPU[0] min_pwr: 0 max_pwr: 279 Mar 25 22:12:52 sparamuseca kernel: schedtune: CPU[1] min_pwr: 0 max_pwr: 279 Mar 25 22:12:52 sparamuseca kernel: schedtune: CLUSTER[2-5] min_pwr: 0 max_pwr: 110 Mar 25 22:12:52 sparamuseca kernel: schedtune: CPU[2] min_pwr: 0 max_pwr: 1048 Mar 25 22:12:52 sparamuseca kernel: schedtune: CPU[3] min_pwr: 0 max_pwr: 1048 Mar 25 22:12:52 sparamuseca kernel: schedtune: CPU[4] min_pwr: 0 max_pwr: 1048 Mar 25 22:12:52 sparamuseca kernel: schedtune: CPU[5] min_pwr: 0 max_pwr: 1048 Mar 25 22:12:52 sparamuseca kernel: schedtune: SYSTEM min_pwr: 0 max_pwr: 4902 Mar 25 22:12:52 sparamuseca kernel: schedtune: using normalization constants mul: 2882587190 sh1: 1 sh2: 12 Mar 25 22:12:52 sparamuseca kernel: schedtune: verify normalization constants... Mar 25 22:12:52 sparamuseca kernel: schedtune: max_pwr/2^0: 4902 => norm_pwr: 1024 Mar 25 22:12:52 sparamuseca kernel: schedtune: max_pwr/2^1: 2451 => norm_pwr: 512 Mar 25 22:12:52 sparamuseca kernel: schedtune: max_pwr/2^2: 1225 => norm_pwr: 255 Mar 25 22:12:52 sparamuseca kernel: schedtune: max_pwr/2^3: 612 => norm_pwr: 127 Mar 25 22:12:52 sparamuseca kernel: schedtune: max_pwr/2^4: 306 => norm_pwr: 63 Mar 25 22:12:52 sparamuseca kernel: schedtune: max_pwr/2^5: 153 => norm_pwr: 31 Mar 25 22:12:52 sparamuseca kernel: schedtune: configured to support global boosting only Mar 25 22:12:52 sparamuseca kernel: cpuidle: using governor menu Mar 25 22:12:52 sparamuseca kernel: register canvas platform driver Mar 25 22:12:52 sparamuseca kernel: register rdma platform driver Mar 25 22:12:52 sparamuseca kernel: vdso: 2 pages (1 code @ ffffff8009c37000, 1 data @ ffffff800a625000) Mar 25 22:12:52 sparamuseca kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 25 22:12:52 sparamuseca kernel: DMA: preallocated 2048 KiB pool for atomic allocations Mar 25 22:12:52 sparamuseca kernel: clkmsr: clkmsr: driver init Mar 25 22:12:52 sparamuseca kernel: codec_mm_module_init Mar 25 22:12:52 sparamuseca kernel: media_configs_system_init Mar 25 22:12:52 sparamuseca kernel: aml_iomap: amlogic iomap probe done Mar 25 22:12:52 sparamuseca kernel: vpu: driver version: v20190329(8-g12b) Mar 25 22:12:52 sparamuseca kernel: vpu: load vpu_clk: 666666667Hz(7) Mar 25 22:12:52 sparamuseca kernel: vpu: clktree_init Mar 25 22:12:52 sparamuseca kernel: vpu: vpu_probe OK Mar 25 22:12:52 sparamuseca kernel: clkmsr: msr_clk_reg0=ffffff800823a004,msr_clk_reg2=ffffff800823c00c Mar 25 22:12:52 sparamuseca kernel: clkmsr ffd18004.meson_clk_msr: failed to get msr ring reg0 Mar 25 22:12:52 sparamuseca kernel: audio_clocks: audio_clocks_probe done Mar 25 22:12:52 sparamuseca kernel: aml_vdac_config_probe: cpu_id:4, name:meson-g12ab-vdac Mar 25 22:12:52 sparamuseca kernel: aml_vdac_probe: ok Mar 25 22:12:52 sparamuseca kernel: canvas_probe reg=00000000ff638000,size=2000 Mar 25 22:12:52 sparamuseca kernel: canvas maped reg_base =ffffff8008268000 Mar 25 22:12:52 sparamuseca kernel: rdma_probe,cpu_type:0, ver:0, len:8 Mar 25 22:12:52 sparamuseca kernel: rdma_register, rdma_table_addr ffffff8008277000 rdma_table_addr_phy cf600000 reg_buf ffffffc0ca160000 Mar 25 22:12:52 sparamuseca kernel: rdma_register success, handle 1 table_size 32768 Mar 25 22:12:52 sparamuseca kernel: set_rdma_handle video rdma handle = 1. Mar 25 22:12:52 sparamuseca kernel: classs created ok Mar 25 22:12:52 sparamuseca kernel: classs file created ok Mar 25 22:12:52 sparamuseca kernel: aml_snd_reg_map[0], reg:ff640000, size:2000 Mar 25 22:12:52 sparamuseca kernel: aml_snd_reg_map[1], reg:ff642000, size:2000 Mar 25 22:12:52 sparamuseca kernel: aml_snd_reg_map[2], reg:ff64a000, size:2000 Mar 25 22:12:52 sparamuseca kernel: aml_snd_reg_map[3], reg:ff656000, size:1800 Mar 25 22:12:52 sparamuseca kernel: aml_snd_reg_map[4], reg:ffd01000, size:1000 Mar 25 22:12:52 sparamuseca kernel: amlogic auge_snd_iomap probe done Mar 25 22:12:52 sparamuseca kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Mar 25 22:12:52 sparamuseca kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Mar 25 22:12:52 sparamuseca kernel: codec_mm_probe ok Mar 25 22:12:52 sparamuseca kernel: cvbs_out: cvbsout_probe, cpu_id:5,name:meson-g12b-cvbsout Mar 25 22:12:52 sparamuseca kernel: cvbs_out: clk path:0 Mar 25 22:12:52 sparamuseca kernel: cvbs_out: cvbs trimming.1.v5: 0xa0, 0x0 Mar 25 22:12:52 sparamuseca kernel: vout: vout1: register server: cvbs_vout_server Mar 25 22:12:52 sparamuseca kernel: cvbs_out: register cvbs module server ok Mar 25 22:12:52 sparamuseca kernel: vout: vout2: register server: cvbs_vout2_server Mar 25 22:12:52 sparamuseca kernel: cvbs_out: register cvbs module vout2 server ok Mar 25 22:12:52 sparamuseca kernel: cvbs_out: chrdev devno 264241152 for disp Mar 25 22:12:52 sparamuseca kernel: cvbs_out: create cdev cvbs Mar 25 22:12:52 sparamuseca kernel: cvbs_out: cvbsout_probe OK Mar 25 22:12:52 sparamuseca kernel: SCSI subsystem initialized Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver usbfs Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver hub Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new device driver usb Mar 25 22:12:52 sparamuseca kernel: media: Linux media interface: v0.10 Mar 25 22:12:52 sparamuseca kernel: Linux video capture interface: v2.00 Mar 25 22:12:52 sparamuseca kernel: pps_core: LinuxPPS API ver. 1 registered Mar 25 22:12:52 sparamuseca kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 25 22:12:52 sparamuseca kernel: PTP clock support registered Mar 25 22:12:52 sparamuseca kernel: dmi: Firmware registration failed. Mar 25 22:12:52 sparamuseca kernel: secmon: reserve_mem_size:0x300000 Mar 25 22:12:52 sparamuseca kernel: secmon secmon: assigned reserved memory node linux,secmon Mar 25 22:12:52 sparamuseca kernel: secmon: get page:ffffffbf00140000, 5000 Mar 25 22:12:52 sparamuseca kernel: secmon: share in base: 0xffffffc0050fe000, share out base: 0xffffffc0050ff000 Mar 25 22:12:52 sparamuseca kernel: secmon: phy_in_base: 0x50fe000, phy_out_base: 0x50ff000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: amhdmitx_probe start Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: Ver: 20190815 Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: hdmitx_device.chip_type : 11 Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: not find match pwr-ctl Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: not find drm_amhdmitx Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: hpd irq = 41 Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: hdcp22_tx_skp failed to probe Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: hdcp22_tx_esm failed to probe Mar 25 22:12:52 sparamuseca kernel: vout: vout1: register server: hdmitx_vout_server Mar 25 22:12:52 sparamuseca kernel: vout: vout2: register server: hdmitx_vout2_server Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xff634400 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xff900000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xff800000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xff63c000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xff608000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xff600000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx20: Mapped PHY: 0xffe01000 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hw: alread display in uboot 0x10 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hw: avmute set to 1 Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: fmt_attr 444,8bit Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: fmt_attr 444,8bit Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: amhdmitx_probe end Mar 25 22:12:52 sparamuseca kernel: vout: create vout attribute OK Mar 25 22:12:52 sparamuseca kernel: vout: vout_fops_create OK Mar 25 22:12:52 sparamuseca kernel: vout: vout1: register server: nulldisp_vout_server Mar 25 22:12:52 sparamuseca kernel: vout: tvout monitor interval:500(ms), timeout cnt:20 Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx_set_current_vmode[3877] Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: recalc before 1080p60hz 60 1 Mar 25 22:12:52 sparamuseca kernel: hdmitx: system: recalc after 1080p60hz 2997 50 Mar 25 22:12:52 sparamuseca kernel: hdmitx: alread display in uboot Mar 25 22:12:52 sparamuseca kernel: vout: init mode 1080p60hz set ok Mar 25 22:12:52 sparamuseca kernel: vout: aml_tvout_mode_monitor Mar 25 22:12:52 sparamuseca kernel: vout: aml_vout_probe OK Mar 25 22:12:52 sparamuseca kernel: chip type:0x29 Mar 25 22:12:52 sparamuseca kernel: MEMORY:[0+ed800000] Mar 25 22:12:52 sparamuseca kernel: ramdump_probe, storage device:data Mar 25 22:12:52 sparamuseca kernel: NO valid ramdump args:0 0 Mar 25 22:12:52 sparamuseca kernel: ramdump_probe, set sticky to 8ed8 Mar 25 22:12:52 sparamuseca kernel: Advanced Linux Sound Architecture Driver Initialized. Mar 25 22:12:52 sparamuseca kernel: NetLabel: Initializing Mar 25 22:12:52 sparamuseca kernel: NetLabel: domain hash size = 128 Mar 25 22:12:52 sparamuseca kernel: NetLabel: protocols = UNLABELED CIPSOv4 Mar 25 22:12:52 sparamuseca kernel: NetLabel: unlabeled traffic allowed by default Mar 25 22:12:52 sparamuseca kernel: clocksource: Switched to clocksource arch_sys_counter Mar 25 22:12:52 sparamuseca kernel: VFS: Disk quotas dquot_6.6.0 Mar 25 22:12:52 sparamuseca kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 2 Mar 25 22:12:52 sparamuseca kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes) Mar 25 22:12:52 sparamuseca kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes) Mar 25 22:12:52 sparamuseca kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 25 22:12:52 sparamuseca kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes) Mar 25 22:12:52 sparamuseca kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 1 Mar 25 22:12:52 sparamuseca kernel: PCI: CLS 0 bytes, default 64 Mar 25 22:12:52 sparamuseca kernel: Unpacking initramfs... Mar 25 22:12:52 sparamuseca kernel: Freeing initrd memory: 8432K Mar 25 22:12:52 sparamuseca kernel: hw perfevents: clusterb_enabled = 1 Mar 25 22:12:52 sparamuseca kernel: hw perfevents: cpumasks 0x3, 0x3c Mar 25 22:12:52 sparamuseca kernel: hw perfevents: cluster A irq = 18 Mar 25 22:12:52 sparamuseca kernel: hw perfevents: cluster B irq = 19 Mar 25 22:12:52 sparamuseca kernel: hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available Mar 25 22:12:52 sparamuseca kernel: audit: initializing netlink subsys (disabled) Mar 25 22:12:52 sparamuseca kernel: audit: type=2000 audit(0.792:1): initialized Mar 25 22:12:52 sparamuseca kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Mar 25 22:12:52 sparamuseca kernel: Registering sdcardfs 0.1 Mar 25 22:12:52 sparamuseca kernel: SELinux: Registering netfilter hooks Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 38 Mar 25 22:12:52 sparamuseca kernel: Key type asymmetric registered Mar 25 22:12:52 sparamuseca kernel: Asymmetric key parser 'x509' registered Mar 25 22:12:52 sparamuseca kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) Mar 25 22:12:52 sparamuseca kernel: io scheduler noop registered (default) Mar 25 22:12:52 sparamuseca kernel: io scheduler deadline registered Mar 25 22:12:52 sparamuseca kernel: io scheduler cfq registered Mar 25 22:12:52 sparamuseca kernel: pwm-gpio pwmgpio: 1 gpio pwms loaded Mar 25 22:12:52 sparamuseca kernel: meson-pwm ff802000.pwm: pwm pinmux : can't get pinctrl Mar 25 22:12:52 sparamuseca kernel: meson-pwm ffd1b000.pwm: pwm pinmux : can't get pinctrl Mar 25 22:12:52 sparamuseca kernel: random: fast init done Mar 25 22:12:52 sparamuseca kernel: random: crng init done Mar 25 22:12:52 sparamuseca kernel: gpiomem-aml ff634000.gpiomem: Initialised: GPIO register area is 1 Mar 25 22:12:52 sparamuseca kernel: gpiomem-aml ff634000.gpiomem: Initialised: Registers at start:0xff634000 end:0xff634fff size:0x00000fff Mar 25 22:12:52 sparamuseca kernel: [drm] Initialized Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: Continuing without Mali regulator control Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: max pp is 2 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: set min pp to default 1 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: min pp is 1 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: min clk is 4 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: hiu io source 0xffffff80083bd000 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: hiu io source 0xffffff80083bf000 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: num of pp used most of time 1 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: clock dvfs cfg table size is 6 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: max clk set 4 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: max clk is 4 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: turbo clk set to 5 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: turbo clk is 5 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: default clk set to 4 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: default clk is 4 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: ====================0==================== clk_freq= 285714285, clk_parent=fclk_div7, voltage=1150, keep_count=5, threshod=<100 190>, clk_sample=285 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: ====================1==================== clk_freq= 400000000, clk_parent=fclk_div5, voltage=1150, keep_count=5, threshod=<152 207>, clk_sample=400 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: ====================2==================== clk_freq= 500000000, clk_parent=fclk_div4, voltage=1150, keep_count=5, threshod=<180 220>, clk_sample=500 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: ====================3==================== clk_freq= 666666666, clk_parent=fclk_div3, voltage=1150, keep_count=5, threshod=<210 236>, clk_sample=666 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: ====================4==================== clk_freq= 800000000, clk_parent=fclk_div2p5, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=800 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: ====================5==================== clk_freq= 800000000, clk_parent=fclk_div2p5, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=800 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: clock dvfs table size is 6 Mar 25 22:12:52 sparamuseca kernel: mali_plat=ffffff800a6b8250 Mar 25 22:12:52 sparamuseca kernel: meson_gcooldev is null, no set min status Mar 25 22:12:52 sparamuseca kernel: gpu cooling register okay with err=0 Mar 25 22:12:52 sparamuseca kernel: meson_gcooldev is null, no set min status Mar 25 22:12:52 sparamuseca kernel: gpu core cooling register okay with err=0 Mar 25 22:12:52 sparamuseca kernel: shader_present=3, tiler_present=1, l2_present=1 Mar 25 22:12:52 sparamuseca kernel: Mali_pwr_on:gpu_irq : 200 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: GPU identified as 0x2 arch 7.2.1 r0p0 status 0 Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: Using coherency mode 0 set from dtb Mar 25 22:12:52 sparamuseca kernel: mali ffe40000.bifrost: Probed as mali0 Mar 25 22:12:52 sparamuseca kernel: Unable to detect cache hierarchy for CPU 0 Mar 25 22:12:52 sparamuseca kernel: loop: module loaded Mar 25 22:12:52 sparamuseca kernel: mtdoops: mtd device (mtddev=name/number) must be supplied Mar 25 22:12:52 sparamuseca kernel: libphy: Fixed MDIO Bus: probed Mar 25 22:12:52 sparamuseca kernel: REG0:Addr = ffffff80083c3540 Mar 25 22:12:52 sparamuseca kernel: ee eth reset:Addr = ffffff80083c5008 Mar 25 22:12:52 sparamuseca kernel: read auto_cali_idx fail Mar 25 22:12:52 sparamuseca kernel: Not set cali_val for REG1 Mar 25 22:12:52 sparamuseca kernel: meson6-dwmac ff3f0000.ethernet: no reset control found Mar 25 22:12:52 sparamuseca kernel: stmmac - user ID: 0x11, Synopsys ID: 0x37 Mar 25 22:12:52 sparamuseca kernel: Ring mode enabled Mar 25 22:12:52 sparamuseca kernel: DMA HW capability register supported Mar 25 22:12:52 sparamuseca kernel: Normal descriptors Mar 25 22:12:52 sparamuseca kernel: RX Checksum Offload Engine supported Mar 25 22:12:52 sparamuseca kernel: COE Type 2 Mar 25 22:12:52 sparamuseca kernel: TX Checksum insertion supported Mar 25 22:12:52 sparamuseca kernel: Wake-Up On Lan supported Mar 25 22:12:52 sparamuseca kernel: eth%d: device MAC address 00:1e:06:43:69:5d Mar 25 22:12:52 sparamuseca kernel: Enable RX Mitigation via HW Watchdog Timer Mar 25 22:12:52 sparamuseca kernel: libphy: stmmac: probed Mar 25 22:12:52 sparamuseca kernel: eth%d: PHY ID 001cc916 at 0 IRQ POLL (stmmac-0:00) active Mar 25 22:12:52 sparamuseca kernel: eth%d: PHY ID 001cc916 at 7 IRQ POLL (stmmac-0:07) Mar 25 22:12:52 sparamuseca kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Mar 25 22:12:52 sparamuseca kernel: ehci-pci: EHCI PCI platform driver Mar 25 22:12:52 sparamuseca kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver cdc_acm Mar 25 22:12:52 sparamuseca kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver usb-storage Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver usbserial Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver usbserial_generic Mar 25 22:12:52 sparamuseca kernel: usbserial: USB Serial support registered for generic Mar 25 22:12:52 sparamuseca kernel: mousedev: PS/2 mouse device common for all mice Mar 25 22:12:52 sparamuseca kernel: i2c /dev entries driver Mar 25 22:12:52 sparamuseca kernel: lirc_helper: wakeupkey 0xffffffff, protocol 0x1 Mar 25 22:12:52 sparamuseca kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 25 22:12:52 sparamuseca kernel: hidraw: raw HID events driver (C) Jiri Kosina Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver usbhid Mar 25 22:12:52 sparamuseca kernel: usbhid: USB HID core driver Mar 25 22:12:52 sparamuseca kernel: meson_cpufreq_init: ignor dsu clk! Mar 25 22:12:52 sparamuseca kernel: meson_cpufreq_init: ignor dsu pre parent clk! Mar 25 22:12:52 sparamuseca kernel: value of voltage_tolerance 0 Mar 25 22:12:52 sparamuseca kernel: meson_cpufreq_init:don't find the node Mar 25 22:12:52 sparamuseca kernel: value of gp1_clk_target 0 Mar 25 22:12:52 sparamuseca kernel: dvfs [meson_cpufreq_init] - cluster 0 freq 2016000 Mar 25 22:12:52 sparamuseca kernel: cpu cpu0: meson_cpufreq_init: CPU 0 initialized Mar 25 22:12:52 sparamuseca kernel: meson_cpufreq_init: ignor dsu clk! Mar 25 22:12:52 sparamuseca kernel: meson_cpufreq_init: ignor dsu pre parent clk! Mar 25 22:12:52 sparamuseca kernel: value of voltage_tolerance 0 Mar 25 22:12:52 sparamuseca kernel: meson_cpufreq_init:don't find the node Mar 25 22:12:52 sparamuseca kernel: value of gp1_clk_target 0 Mar 25 22:12:52 sparamuseca kernel: dvfs [meson_cpufreq_init] - cluster 1 freq 2304000 Mar 25 22:12:52 sparamuseca kernel: dvfs [meson_cpufreq_init] - cluster 1 freq 2400000 Mar 25 22:12:52 sparamuseca kernel: cpu cpu2: meson_cpufreq_init: CPU 2 initialized Mar 25 22:12:52 sparamuseca kernel: ff803000.serial: clock gate not found Mar 25 22:12:52 sparamuseca kernel: meson_uart ff803000.serial: ==uart0 reg addr = ffffff80083cb000 Mar 25 22:12:52 sparamuseca kernel: ff803000.serial: ttyS0 at MMIO 0xff803000 (irq = 31, base_baud = 1500000) is a meson_uart Mar 25 22:12:52 sparamuseca kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 0 to 115200 Mar 25 22:12:52 sparamuseca kernel: console [ttyS0] enabled Mar 25 22:12:52 sparamuseca kernel: amlogic-new-usb2-v2 ffe09000.usb2phy: USB2 phy probe:phy_mem:0xffe09000, iomap phy_base:0xffffff80083cd000 Mar 25 22:12:52 sparamuseca kernel: amlogic-new-usb3-v2 ffe09080.usb3phy: USB3 phy probe:phy_mem:0xffe09080, iomap phy_base:0xffffff80083eb080 Mar 25 22:12:52 sparamuseca kernel: aml_dma ff63e000.aml_dma: Aml dma Mar 25 22:12:52 sparamuseca kernel: aml_aes_dma ff63e000.aml_dma:aml_aes: Aml AES_dma Mar 25 22:12:52 sparamuseca kernel: aml_tdes_dma ff63e000.aml_dma:aml_tdes: Aml TDES_dma Mar 25 22:12:52 sparamuseca kernel: aml_sha_dma ff63e000.aml_dma:aml_sha: Aml SHA1/SHA224/SHA256 dma Mar 25 22:12:52 sparamuseca kernel: gpio-keypad ff800000.gpio_keypad: power key(116) registed. Mar 25 22:12:52 sparamuseca kernel: input: gpio_keypad as /devices/platform/ff800000.gpio_keypad/input/input0 Mar 25 22:12:52 sparamuseca kernel: efusekeynum: 1 Mar 25 22:12:52 sparamuseca kernel: efusekeyname: uuid offset: 0 size: 32 Mar 25 22:12:52 sparamuseca kernel: efuse efuse: probe OK! Mar 25 22:12:52 sparamuseca kernel: ion_dev soc:ion_dev: assigned reserved memory node linux,ion-dev Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d_init_module Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d_dev major:236 Mar 25 22:12:52 sparamuseca kernel: ge2d: clock source clk_ge2d_gate ffffffc0ca12c600 Mar 25 22:12:52 sparamuseca kernel: ge2d: clock clk_ge2d source ffffffc0ca12c680 Mar 25 22:12:52 sparamuseca kernel: ge2d: clock source clk_vapb_0 ffffffc0ca12c700 Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d init clock is 500000000 HZ, VPU clock is 666666656 HZ Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d clock is 499 MHZ Mar 25 22:12:52 sparamuseca kernel: ge2d: find address resource Mar 25 22:12:52 sparamuseca kernel: ge2d: map io source 0x00000000ff940000,size=65536 to 0xffffff8008590000 Mar 25 22:12:52 sparamuseca kernel: ge2d: reserved mem init failed Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d: pdev=ffffffc0ca150c00, irq=44, clk=ffffffc0ca12c600 Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d start monitor Mar 25 22:12:52 sparamuseca kernel: ge2d: ge2d workqueue monitor start Mar 25 22:12:52 sparamuseca kernel: [tsync_pcr_init]init success. Mar 25 22:12:52 sparamuseca kernel: amvideom vsync irq: 45 Mar 25 22:12:52 sparamuseca kernel: create_ge2d_work_queue video task ok Mar 25 22:12:52 sparamuseca kernel: fb: osd_init_module Mar 25 22:12:52 sparamuseca kernel: fb: viu vsync irq: 45 Mar 25 22:12:52 sparamuseca kernel: fb: viu2 vsync irq: 54 Mar 25 22:12:52 sparamuseca kernel: 0x000000db:Y=db,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000dc:Y=dc,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000dd:Y=dd,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000de:Y=de,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000df:Y=df,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000e0:Y=e0,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: fb: osd_rdma_init: rdma_table p=0xcf608000,op=0xcf608000 , v=0xffffff80083f7000 Mar 25 22:12:52 sparamuseca kernel: rdma_register, rdma_table_addr ffffff80083f9000 rdma_table_addr_phy cf609000 reg_buf ffffffc0ca3e6000 Mar 25 22:12:52 sparamuseca kernel: rdma_register success, handle 2 table_size 4096 Mar 25 22:12:52 sparamuseca kernel: fb: osd_rdma_init:osd rdma handle = 2. Mar 25 22:12:52 sparamuseca kernel: fb: mem_size: 0x800000 Mar 25 22:12:52 sparamuseca kernel: fb: mem_size: 0x4b80000 Mar 25 22:12:52 sparamuseca kernel: fb: mem_size: 0x100000 Mar 25 22:12:52 sparamuseca kernel: fb: mem_size: 0x100000 Mar 25 22:12:52 sparamuseca kernel: fb: mem_size: 0x800000 Mar 25 22:12:52 sparamuseca kernel: meson-fb meson-fb: assigned reserved memory node linux,meson-fb Mar 25 22:12:52 sparamuseca kernel: fb: reserved memory base:0x000000007f800000, size:800000 Mar 25 22:12:52 sparamuseca kernel: fb: fb def : 1920 1080 1920 2160 32 Mar 25 22:12:52 sparamuseca kernel: fb: init fbdev bpp is:32 Mar 25 22:12:52 sparamuseca kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Mar 25 22:12:52 sparamuseca kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Mar 25 22:12:52 sparamuseca kernel: fb: use ion buffer for fb memory, fb_index=0 Mar 25 22:12:52 sparamuseca kernel: fb: OSD0 as afbcd mode,afbc_type=2 Mar 25 22:12:52 sparamuseca kernel: meson-fb meson-fb: create ion_client ffffffc0ca12f9c0, handle=ffffffc0ca12c1c0 Mar 25 22:12:52 sparamuseca kernel: meson-fb meson-fb: ion memory(0): created fb at 0x00000000e5800000, size 75 MiB Mar 25 22:12:52 sparamuseca kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Mar 25 22:12:52 sparamuseca kernel: fb: Frame buffer memory assigned at Mar 25 22:12:52 sparamuseca kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Mar 25 22:12:52 sparamuseca kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Mar 25 22:12:52 sparamuseca kernel: fb: logo_index=0,fb_index=0 Mar 25 22:12:52 sparamuseca kernel: fb: ---------------clear fb0 memory ffffff800a94f000 Mar 25 22:12:52 sparamuseca kernel: clear:c5800000, free:c5800000, tick:582068 us Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] canvas.idx =0x40 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] canvas.addr=0xe5800000 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] canvas.width=7680 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] canvas.height=2160 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] frame.width=1920 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] frame.height=1080 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] out_addr_id =0x1 Mar 25 22:12:52 sparamuseca kernel: Console: switching to colour frame buffer device 240x67 Mar 25 22:12:52 sparamuseca kernel: fb: osd[0] enable: 1 (swapper/0) Mar 25 22:12:52 sparamuseca kernel: fb: set osd0 reverse as NONE Mar 25 22:12:52 sparamuseca kernel: fb: osd probe OK Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdcp: hdmitx_hdcp_init Mar 25 22:12:52 sparamuseca kernel: vout: vout2: create vout2 attribute OK Mar 25 22:12:52 sparamuseca kernel: vout: vout2: vout2_fops_create OK Mar 25 22:12:52 sparamuseca kernel: vout: vout2: clktree_init Mar 25 22:12:52 sparamuseca kernel: vout: vout2: register server: nulldisp_vout2_server Mar 25 22:12:52 sparamuseca kernel: vout: vout2: init mode null set ok Mar 25 22:12:52 sparamuseca kernel: vout: vout2: aml_vout2_probe OK Mar 25 22:12:52 sparamuseca kernel: DI: di_module_init ok. Mar 25 22:12:52 sparamuseca kernel: DI: di_probe: Mar 25 22:12:52 sparamuseca kernel: DI: di_probe: major 510 Mar 25 22:12:52 sparamuseca kernel: deinterlace deinterlace: assigned reserved memory node linux,di_cma Mar 25 22:12:52 sparamuseca kernel: di:flag_cma=1 Mar 25 22:12:52 sparamuseca kernel: DI: CMA size 0x2800000. Mar 25 22:12:52 sparamuseca kernel: pre_irq:68 Mar 25 22:12:52 sparamuseca kernel: post_irq:69 Mar 25 22:12:52 sparamuseca kernel: DI: di_probe allocate rdma channel 0. Mar 25 22:12:52 sparamuseca kernel: di_get_vpu_clkb: get clk vpu error. Mar 25 22:12:52 sparamuseca kernel: DI: vpu clkb <334000000, 667000000> Mar 25 22:12:52 sparamuseca kernel: get clkb rate:333333328 Mar 25 22:12:52 sparamuseca kernel: DI:enable vpu clkb. Mar 25 22:12:52 sparamuseca kernel: 0x000000e1:Y=e1,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000e2:Y=e2,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000e3:Y=e3,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f0:Y=f0,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f1:Y=f1,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f2:Y=f2,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f3:Y=f3,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f4:Y=f4,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f5:Y=f5,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f6:Y=f6,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f7:Y=f7,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f8:Y=f8,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000f9:Y=f9,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000fa:Y=fa,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000fb:Y=fb,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000fc:Y=fc,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000fd:Y=fd,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000fe:Y=fe,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x000000ff:Y=ff,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x0000003a:Y=3a,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x0000003b:Y=3b,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x0000003c:Y=3c,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x0000003d:Y=3d,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x0000003e:Y=3e,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: 0x0000003f:Y=3f,U=0,V=0 Mar 25 22:12:52 sparamuseca kernel: DI: support multi decoding 61~62~63. Mar 25 22:12:52 sparamuseca kernel: DI: di_probe:Di use HRTIMER Mar 25 22:12:52 sparamuseca kernel: DI: di_probe:ok Mar 25 22:12:52 sparamuseca kernel: dim:dim_module_init Mar 25 22:12:52 sparamuseca kernel: dim:dim_module_init finish Mar 25 22:12:52 sparamuseca kernel: dil:dil_init. Mar 25 22:12:52 sparamuseca kernel: dil:dil_init ok. Mar 25 22:12:52 sparamuseca kernel: vdin_drv_init: major 509 Mar 25 22:12:52 sparamuseca kernel: vdin_drv_init: vdin driver init done Mar 25 22:12:52 sparamuseca kernel: [viuin..]viuin_init_module viuin module init Mar 25 22:12:52 sparamuseca kernel: [viuin..]viuin_probe probe ok. Mar 25 22:12:52 sparamuseca kernel: [RX]-hdmirx: hdmirx_init. Mar 25 22:12:52 sparamuseca kernel: ESM HLD: Initializing... Mar 25 22:12:52 sparamuseca kernel: amlvid:info: amlvideo_init called Mar 25 22:12:52 sparamuseca kernel: amlvid:info: amlvideo_create_instance called Mar 25 22:12:52 sparamuseca kernel: amlvid:info: v4l2_dev.name=:amlvideo-000 Mar 25 22:12:52 sparamuseca kernel: amlvideo-000: V4L2 device registered as video10 Mar 25 22:12:52 sparamuseca kernel: amlvid:info: amlvideo_create_instance called Mar 25 22:12:52 sparamuseca kernel: amlvid:info: v4l2_dev.name=:amlvideo-001 Mar 25 22:12:52 sparamuseca kernel: amlvideo-001: V4L2 device registered as video23 Mar 25 22:12:52 sparamuseca kernel: PPMGRDRV: warn: ppmgr module init func called Mar 25 22:12:52 sparamuseca kernel: PPMGRDRV: info: ppmgr_driver_probe called Mar 25 22:12:52 sparamuseca kernel: Reserved memory: failed to init DMA memory pool at 0x00000000e3000000, size 0 MiB Mar 25 22:12:52 sparamuseca kernel: PPMGRDRV: info: ppmgr_dev major:507 Mar 25 22:12:52 sparamuseca kernel: ionvideo-000: V4L2 device registered as video13 Mar 25 22:12:52 sparamuseca kernel: ionvideo-001: V4L2 device registered as video14 Mar 25 22:12:52 sparamuseca kernel: ionvideo-002: V4L2 device registered as video15 Mar 25 22:12:52 sparamuseca kernel: ionvideo-003: V4L2 device registered as video16 Mar 25 22:12:52 sparamuseca kernel: ionvideo-004: V4L2 device registered as video17 Mar 25 22:12:52 sparamuseca kernel: ionvideo-005: V4L2 device registered as video18 Mar 25 22:12:52 sparamuseca kernel: ionvideo-006: V4L2 device registered as video19 Mar 25 22:12:52 sparamuseca kernel: ionvideo-007: V4L2 device registered as video20 Mar 25 22:12:52 sparamuseca kernel: ionvideo-008: V4L2 device registered as video21 Mar 25 22:12:52 sparamuseca kernel: ionvid: info: Video Technology Magazine Ion Video Mar 25 22:12:52 sparamuseca kernel: ionvid: info: Capture Board ver 1.0 successfully loaded Mar 25 22:12:52 sparamuseca kernel: videosync_create_instance dev_s ffffffc0b9ff0c00,dev_s->dev ffffffc0b9fc6300 Mar 25 22:12:52 sparamuseca kernel: videosync_create_instance reg videosync.0 Mar 25 22:12:52 sparamuseca kernel: aml_vecm_init:module init Mar 25 22:12:52 sparamuseca kernel: videosync_thread started Mar 25 22:12:52 sparamuseca kernel: VECM probe start Mar 25 22:12:52 sparamuseca kernel: Can't find detect_colorbar. Mar 25 22:12:52 sparamuseca kernel: Can't find detect_face. Mar 25 22:12:52 sparamuseca kernel: Can't find detect_corn. Mar 25 22:12:52 sparamuseca kernel: Can't find wb_sel. Mar 25 22:12:52 sparamuseca kernel: hdr:Can't find cfg_en_osd_100. Mar 25 22:12:52 sparamuseca kernel: Can't find tx_op_color_primary. Mar 25 22:12:52 sparamuseca kernel: amlogic, vecm Mar 25 22:12:52 sparamuseca kernel: vlock dt support: 1 Mar 25 22:12:52 sparamuseca kernel: vlock dt new_fsm: 0 Mar 25 22:12:52 sparamuseca kernel: vlock dt hwver: 0 Mar 25 22:12:52 sparamuseca kernel: vlock dt phlock_en: 0 Mar 25 22:12:52 sparamuseca kernel: Can't find vlock_en. Mar 25 22:12:52 sparamuseca kernel: Can't find vlock_mode. Mar 25 22:12:52 sparamuseca kernel: Can't find vlock_pll_m_limit. Mar 25 22:12:52 sparamuseca kernel: Can't find vlock_line_limit. Mar 25 22:12:52 sparamuseca kernel: param_config vlock_en:1 md=0x4 Mar 25 22:12:52 sparamuseca kernel: vlock: maxLine 524,maxPixel 1715 Mar 25 22:12:52 sparamuseca kernel: vlock_status_init vlock_en:1 Mar 25 22:12:52 sparamuseca kernel: aml_vecm_probe: ok Mar 25 22:12:52 sparamuseca kernel: amdolby_vision_init:module init Mar 25 22:12:52 sparamuseca kernel: amdolby_vision probe start & ver: 20181220 Mar 25 22:12:52 sparamuseca kernel: cpu_id=2 tvmode=0 Mar 25 22:12:52 sparamuseca kernel: dolby_vision_init_receiver(dvel) Mar 25 22:12:52 sparamuseca kernel: dolby_vision_init_receiver: dvel Mar 25 22:12:52 sparamuseca kernel: amdolby_vision_probe: ok Mar 25 22:12:52 sparamuseca kernel: dovi disable in uboot Mar 25 22:12:52 sparamuseca kernel: reg base = ffffff8008587000 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: mmc driver version: 3.02, 2017-05-15: New Emmc Host Controller Mar 25 22:12:52 sparamuseca kernel: meson-mmc: >>>>>>>>hostbase ffffff80085a1000, dmode Mar 25 22:12:52 sparamuseca kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Mar 25 22:12:52 sparamuseca kernel: hdmitx: hdmitx_set_drm_pkt: tf=1, cf=1, colormetry=0 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: meson_mmc_probe() : success! Mar 25 22:12:52 sparamuseca kernel: meson-mmc: >>>>>>>>hostbase ffffff80085aa000, dmode Mar 25 22:12:52 sparamuseca kernel: meson-mmc: gpio_cd = 1ca Mar 25 22:12:52 sparamuseca kernel: meson-mmc: meson_mmc_probe() : success! Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: cec driver date:2019/10/22: finetune ARB rising time Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: compatible:amlogic, aocec-g12a Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: cecb_ver:0x1 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: line_reg:0x1 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: line_bit:0x3 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: ee_to_ao:0x1 Mar 25 22:12:52 sparamuseca kernel: input: cec_input as /devices/virtual/input/input1 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: not find 'port_num' Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: using cec:1 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: no hdmirx regs Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: no hhi regs Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: not find 'output' Mar 25 22:12:52 sparamuseca kernel: irq cnt:2, a:43, b42 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: wakeup_reason:0x0 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: cev val1: 0x0;val2: 0x0 Mar 25 22:12:52 sparamuseca kernel: cectx ff80023c.aocec: aml_cec_probe success end Mar 25 22:12:52 sparamuseca kernel: unifykey: storage in base: 0xffffffc005000000 Mar 25 22:12:52 sparamuseca kernel: unifykey: storage out base: 0xffffffc005040000 Mar 25 22:12:52 sparamuseca kernel: unifykey: storage block base: 0xffffffc005080000 Mar 25 22:12:52 sparamuseca kernel: unifykey: probe done! Mar 25 22:12:52 sparamuseca kernel: unifykey: aml_unifykeys_init done! Mar 25 22:12:52 sparamuseca kernel: meson ts init Mar 25 22:12:52 sparamuseca kernel: tsensor id: 0 Mar 25 22:12:52 sparamuseca kernel: r1p1_tsensor_read valid cnt is 0 Mar 25 22:12:52 sparamuseca kernel: tsensor trim info: 0xfa008102! Mar 25 22:12:52 sparamuseca kernel: tsensor hireboot: 0xc0ff2b00 Mar 25 22:12:52 sparamuseca kernel: meson ts init Mar 25 22:12:52 sparamuseca kernel: tsensor id: 1 Mar 25 22:12:52 sparamuseca kernel: r1p1_tsensor_read valid cnt is 0 Mar 25 22:12:52 sparamuseca kernel: tsensor trim info: 0xfa0080c0! Mar 25 22:12:52 sparamuseca kernel: tsensor hireboot: 0xc0ff2af0 Mar 25 22:12:52 sparamuseca kernel: audio_dsp: [dsp]register dsp to char divece(257) Mar 25 22:12:52 sparamuseca kernel: amaudio: amaudio: driver amaudio init! Mar 25 22:12:52 sparamuseca kernel: amaudio: amaudio_init - amaudio: driver amaudio succuess! Mar 25 22:12:52 sparamuseca kernel: amlkaraoke init success! Mar 25 22:12:52 sparamuseca kernel: sysled: module init Mar 25 22:12:52 sparamuseca kernel: meson_wdt ffd0f0d0.watchdog: start watchdog Mar 25 22:12:52 sparamuseca kernel: meson_wdt ffd0f0d0.watchdog: creat work queue for watch dog Mar 25 22:12:52 sparamuseca kernel: meson_wdt ffd0f0d0.watchdog: AML Watchdog Timer probed done Mar 25 22:12:52 sparamuseca kernel: meson-saradc ff809000.saradc: set delay per tick to <1ms> by default. Mar 25 22:12:52 sparamuseca kernel: meson-saradc ff809000.saradc: set ticks per period to <1> by default. Mar 25 22:12:52 sparamuseca kernel: dmc_monitor_probe Mar 25 22:12:52 sparamuseca kernel: atv_demod: aml_atvdemod_init: OK, atv demod version: V2.15. Mar 25 22:12:52 sparamuseca kernel: defendkey ff630218.defendkey: Reserved memory is not enough! Mar 25 22:12:52 sparamuseca kernel: defendkey: probe of ff630218.defendkey failed with error -22 Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver snd-usb-audio Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: aml_T9015_audio_codec_probe Mar 25 22:12:52 sparamuseca kernel: T9015 acodec used by auge, tdmout:2 Mar 25 22:12:52 sparamuseca kernel: Error: Driver 'spdif-dit' is already registered, aborting... Mar 25 22:12:52 sparamuseca kernel: asoc debug: aml_audio_controller_probe-130 Mar 25 22:12:52 sparamuseca kernel: aml_tdm_platform_probe, tdm ID = 2, lane_cnt = 4 Mar 25 22:12:52 sparamuseca kernel: TDM id 2 i2s2hdmi:0 Mar 25 22:12:52 sparamuseca kernel: snd_tdm ff642000.audiobus:tdmc: lane_mask_out = 1, lane_oe_mask_out = 0 Mar 25 22:12:52 sparamuseca kernel: snd_tdm ff642000.audiobus:tdmc: neither mclk_pad nor mclk2pad set Mar 25 22:12:52 sparamuseca kernel: snd_tdm ff642000.audiobus:tdmc: aml_tdm_get_pins error! Mar 25 22:12:52 sparamuseca kernel: aml_tdm_platform_probe(), share en = 1 Mar 25 22:12:52 sparamuseca kernel: No channel mask node Channel_Mask Mar 25 22:12:52 sparamuseca kernel: aml_spdif_platform_probe, spdif ID = 0 Mar 25 22:12:52 sparamuseca kernel: SPDIF id 0 asrc_id:0 auto_asrc:0 Mar 25 22:12:52 sparamuseca kernel: spdifout_play_with_zerodata, spdif id:0 enable:0 Mar 25 22:12:52 sparamuseca kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Mar 25 22:12:52 sparamuseca kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Mar 25 22:12:52 sparamuseca kernel: hdmitx: audio: aout notify format CT_PCM Mar 25 22:12:52 sparamuseca kernel: spdif_a is set to enable Mar 25 22:12:52 sparamuseca kernel: aml_spdif_platform_probe, register soc platform Mar 25 22:12:52 sparamuseca kernel: aml_spdif_platform_probe, spdif ID = 1 Mar 25 22:12:52 sparamuseca kernel: spdifout_play_with_zerodata, spdif id:1 enable:0 Mar 25 22:12:52 sparamuseca kernel: spdif_b fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Mar 25 22:12:52 sparamuseca kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Mar 25 22:12:52 sparamuseca kernel: spdif_b is set to enable Mar 25 22:12:52 sparamuseca kernel: aml_spdif_platform_probe, register soc platform Mar 25 22:12:52 sparamuseca kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 0, irqs toddr 32, frddr 35 Mar 25 22:12:52 sparamuseca kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 1, irqs toddr 33, frddr 36 Mar 25 22:12:52 sparamuseca kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 2, irqs toddr 34, frddr 37 Mar 25 22:12:52 sparamuseca kernel: audiolocker_platform_probe Mar 25 22:12:52 sparamuseca kernel: Register vad Mar 25 22:12:52 sparamuseca kernel: meson-aml-mmc ffe07000.emmc: divider requested rate 200000000 != actual rate 199999997: ret=0 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: Data 1 aligned delay is 0 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: clk 199999997 tuning start Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: adj_win: < 1 2 3 > Mar 25 22:12:52 sparamuseca kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: adj_win: < 1 2 3 > Mar 25 22:12:52 sparamuseca kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: adj_win: < 1 2 3 4 > Mar 25 22:12:52 sparamuseca kernel: meson-mmc: step:12, delay1:0xc30c30c, delay2:0xc00c30c Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: adj_win: < 1 2 3 4 > Mar 25 22:12:52 sparamuseca kernel: meson-mmc: step:16, delay1:0x10410410, delay2:0x10010410 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: adj_win: < 1 2 3 4 > Mar 25 22:12:52 sparamuseca kernel: meson-mmc: step:20, delay1:0x14514514, delay2:0x14014514 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: adj_win: < 2 3 4 > Mar 25 22:12:52 sparamuseca kernel: meson-mmc: left:2, right:0, mid:3, size:3 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: emmc: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x22000 Mar 25 22:12:52 sparamuseca kernel: meson-mmc: delay1:0x0, delay2:0x0 Mar 25 22:12:52 sparamuseca kernel: emmc: new HS200 MMC card at address 0001 Mar 25 22:12:52 sparamuseca kernel: emmc: clock 199999997, 8-bit-bus-width Mar 25 22:12:52 sparamuseca kernel: mmcblk0: emmc:0001 Y29128 117 GiB Mar 25 22:12:52 sparamuseca kernel: mmcblk0boot0: emmc:0001 Y29128 partition 1 4.00 MiB Mar 25 22:12:52 sparamuseca kernel: mmcblk0boot1: emmc:0001 Y29128 partition 2 4.00 MiB Mar 25 22:12:52 sparamuseca kernel: mmcblk0rpmb: emmc:0001 Y29128 partition 3 4.00 MiB Mar 25 22:12:52 sparamuseca kernel: mmcblk0: p1 p2 p3 Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLP_SEL_DACL -> Lineout left P switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLP_SEL_DACL_INV -> Lineout left P switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLN_SEL_DACL_INV -> Lineout left N switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLN_SEL_DACL -> Lineout left N switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORP_SEL_DACR -> Lineout right P switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORP_SEL_DACR_INV -> Lineout right P switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORN_SEL_DACR_INV -> Lineout right N switch Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORN_SEL_DACR -> Lineout right N switch Mar 25 22:12:52 sparamuseca kernel: aml_dai_spdif_probe Mar 25 22:12:52 sparamuseca kernel: aml_dai_spdif_probe Mar 25 22:12:52 sparamuseca kernel: set normal 512 fs /4 fs Mar 25 22:12:52 sparamuseca kernel: set spdifout clk:6144000, mpll:24576000 Mar 25 22:12:52 sparamuseca kernel: get spdifout clk:6143997, mpll:24575987 Mar 25 22:12:52 sparamuseca kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Mar 25 22:12:52 sparamuseca kernel: aml_spdif_new spdif_b, clk continuous:1 Mar 25 22:12:52 sparamuseca kernel: spdifout_play_with_zerodata_free, spdif id:1 Mar 25 22:12:52 sparamuseca kernel: asoc-aml-card auge_sound: dit-hifi <-> SPDIF-B mapping ok Mar 25 22:12:52 sparamuseca kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Mar 25 22:12:52 sparamuseca kernel: aml_dai_set_tdm_slot(), txmask(0x3), rxmask(0x3) Mar 25 22:12:52 sparamuseca kernel: lanes_out_cnt(1), lanes_in_cnt(1) Mar 25 22:12:52 sparamuseca kernel: lanes_lb_cnt(0) Mar 25 22:12:52 sparamuseca kernel: slots(2), slot_width(32) Mar 25 22:12:52 sparamuseca kernel: lanes_oe_out_cnt(0), lanes_oe_in_cnt(0) Mar 25 22:12:52 sparamuseca kernel: asoc aml_dai_set_tdm_fmt, 0x4011, ffffffc0ca381c18, id(2), clksel(2) Mar 25 22:12:52 sparamuseca kernel: master_mode(1), binv(1), finv(1) out_skew(2), in_skew(3) Mar 25 22:12:52 sparamuseca kernel: asoc-aml-card auge_sound: T9015-audio-hifi <-> TDM-C mapping ok Mar 25 22:12:52 sparamuseca kernel: set normal 512 fs /4 fs Mar 25 22:12:52 sparamuseca kernel: set spdifout clk:6144000, mpll:24576000 Mar 25 22:12:52 sparamuseca kernel: get spdifout clk:6143997, mpll:24575987 Mar 25 22:12:52 sparamuseca kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Mar 25 22:12:52 sparamuseca kernel: aml_spdif_new spdif_a, clk continuous:1 Mar 25 22:12:52 sparamuseca kernel: spdifout_play_with_zerodata_free, spdif id:0 Mar 25 22:12:52 sparamuseca kernel: asoc-aml-card auge_sound: dit-hifi <-> SPDIF mapping ok Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout left P switch has no paths Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout left N switch has no paths Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout right P switch has no paths Mar 25 22:12:52 sparamuseca kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout right N switch has no paths Mar 25 22:12:52 sparamuseca kernel: snd_card_add_kcontrols card:ffffffc0b9feb018 Mar 25 22:12:52 sparamuseca kernel: effect_v2 is not init Mar 25 22:12:52 sparamuseca kernel: Not init vad Mar 25 22:12:52 sparamuseca kernel: Failed to add VAD controls Mar 25 22:12:52 sparamuseca kernel: eq/drc v1 function enable Mar 25 22:12:52 sparamuseca kernel: Netfilter messages via NETLINK v0.30. Mar 25 22:12:52 sparamuseca kernel: Initializing XFRM netlink socket Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 17 Mar 25 22:12:52 sparamuseca kernel: Key type dns_resolver registered Mar 25 22:12:52 sparamuseca kernel: Registered swp emulation handler Mar 25 22:12:52 sparamuseca kernel: Registered cp15_barrier emulation handler Mar 25 22:12:52 sparamuseca kernel: Registered setend emulation handler Mar 25 22:12:52 sparamuseca kernel: registered taskstats version 1 Mar 25 22:12:52 sparamuseca kernel: dwc3 ff500000.dwc3: Configuration mismatch. dr_mode forced to host Mar 25 22:12:52 sparamuseca kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Mar 25 22:12:52 sparamuseca kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 Mar 25 22:12:52 sparamuseca kernel: usb: xhci: determined sg_tablesize: 4294967295 Mar 25 22:12:52 sparamuseca kernel: xhci-hcd xhci-hcd.0.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x20010010 Mar 25 22:12:52 sparamuseca kernel: xhci-hcd xhci-hcd.0.auto: irq 23, io mem 0xff500000 Mar 25 22:12:52 sparamuseca kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Mar 25 22:12:52 sparamuseca kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 25 22:12:52 sparamuseca kernel: usb usb1: Product: xHCI Host Controller Mar 25 22:12:52 sparamuseca kernel: usb usb1: Manufacturer: Linux 4.9.241+ xhci-hcd Mar 25 22:12:52 sparamuseca kernel: usb usb1: SerialNumber: xhci-hcd.0.auto Mar 25 22:12:52 sparamuseca kernel: hub 1-0:1.0: USB hub found Mar 25 22:12:52 sparamuseca kernel: hub 1-0:1.0: 2 ports detected Mar 25 22:12:52 sparamuseca kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Mar 25 22:12:52 sparamuseca kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 Mar 25 22:12:52 sparamuseca kernel: usb: xhci: determined sg_tablesize: 4294967295 Mar 25 22:12:52 sparamuseca kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Mar 25 22:12:52 sparamuseca kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 Mar 25 22:12:52 sparamuseca kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 25 22:12:52 sparamuseca kernel: usb usb2: Product: xHCI Host Controller Mar 25 22:12:52 sparamuseca kernel: usb usb2: Manufacturer: Linux 4.9.241+ xhci-hcd Mar 25 22:12:52 sparamuseca kernel: usb usb2: SerialNumber: xhci-hcd.0.auto Mar 25 22:12:52 sparamuseca kernel: hub 2-0:1.0: USB hub found Mar 25 22:12:52 sparamuseca kernel: hub 2-0:1.0: 1 port detected Mar 25 22:12:52 sparamuseca kernel: hctosys: unable to open rtc device (rtc0) Mar 25 22:12:52 sparamuseca kernel: dwc_otg ff400000.dwc2_a: dwc_otg_driver_probe(ffffffc0ca120800) Mar 25 22:12:52 sparamuseca kernel: dwc_otg: usb0: type: 2 speed: 0, config: 0, dma: 0, id: 0, phy: ffe09000, ctrl: 0 Mar 25 22:12:52 sparamuseca kernel: dwc_otg ff400000.dwc2_a: base=0xffffff8008c00000 Mar 25 22:12:52 sparamuseca kernel: dwc_otg ff400000.dwc2_a: dwc_otg_device=0xffffffc0c9936e00 Mar 25 22:12:52 sparamuseca kernel: dwc_otg: Core Release: 3.30a Mar 25 22:12:52 sparamuseca kernel: dwc_otg: Setting default values for core params Mar 25 22:12:52 sparamuseca kernel: dwc_otg: curmode: 0, host_only: 0 Mar 25 22:12:52 sparamuseca kernel: dwc_otg ff400000.dwc2_a: DMA config: BURST_DEFAULT Mar 25 22:12:52 sparamuseca kernel: dwc_otg: Using Buffer DMA mode Mar 25 22:12:52 sparamuseca kernel: dwc_otg: OTG VER PARAM: 1, OTG VER FLAG: 1 Mar 25 22:12:52 sparamuseca kernel: dwc_otg: Working on port type = SLAVE Mar 25 22:12:52 sparamuseca kernel: dwc_otg: Dedicated Tx FIFOs mode Mar 25 22:12:52 sparamuseca kernel: meson_cdev probe Mar 25 22:12:52 sparamuseca kernel: meson_cdev index: 0 Mar 25 22:12:52 sparamuseca kernel: thermal thermal_zone0: binding zone soc_thermal with cdev thermal-cpufreq-0 failed:-22 Mar 25 22:12:52 sparamuseca kernel: meson_cdev index: 1 Mar 25 22:12:52 sparamuseca kernel: thermal: no cluster id, cpucore as one cooldev Mar 25 22:12:52 sparamuseca kernel: meson_cdev index: 2 Mar 25 22:12:52 sparamuseca kernel: cpucore_cooling_register, max_cpu_core_num:6 Mar 25 22:12:52 sparamuseca kernel: cpucore_cooling_register, clutser[1] core num:4 Mar 25 22:12:52 sparamuseca kernel: cpucore_cooling_register, clutser[0] core num:2 Mar 25 22:12:52 sparamuseca kernel: meson_cdev index: 3 Mar 25 22:12:52 sparamuseca kernel: meson_cdev index: 4 Mar 25 22:12:52 sparamuseca kernel: find tzd id: 0 Mar 25 22:12:52 sparamuseca kernel: find tzd id: 0 Mar 25 22:12:52 sparamuseca kernel: find tzd id: 0 Mar 25 22:12:52 sparamuseca kernel: meson_cdev probe done Mar 25 22:12:52 sparamuseca kernel: gxbb_pm: enter meson_pm_probe! Mar 25 22:12:52 sparamuseca kernel: no vddio3v3_en pin Mar 25 22:12:52 sparamuseca kernel: pm-meson aml_pm: Can't get switch_clk81 Mar 25 22:12:52 sparamuseca kernel: gxbb_pm: meson_pm_probe done Mar 25 22:12:52 sparamuseca kernel: ALSA device list: Mar 25 22:12:52 sparamuseca kernel: #0: AML-AUGESOUND Mar 25 22:12:52 sparamuseca kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 115200 to 115200 Mar 25 22:12:52 sparamuseca kernel: Freeing unused kernel memory: 4992K Mar 25 22:12:52 sparamuseca kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Mar 25 22:12:52 sparamuseca kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Mar 25 22:12:52 sparamuseca kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 25 22:12:52 sparamuseca kernel: usb 1-1: Product: USB2.1 Hub Mar 25 22:12:52 sparamuseca kernel: usb 1-1: Manufacturer: GenesysLogic Mar 25 22:12:52 sparamuseca kernel: hub 1-1:1.0: USB hub found Mar 25 22:12:52 sparamuseca kernel: hub 1-1:1.0: 4 ports detected Mar 25 22:12:52 sparamuseca kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd Mar 25 22:12:52 sparamuseca kernel: usb 2-1: New USB device found, idVendor=05e3, idProduct=0620 Mar 25 22:12:52 sparamuseca kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 25 22:12:52 sparamuseca kernel: usb 2-1: Product: USB3.1 Hub Mar 25 22:12:52 sparamuseca kernel: usb 2-1: Manufacturer: GenesysLogic Mar 25 22:12:52 sparamuseca kernel: hub 2-1:1.0: USB hub found Mar 25 22:12:52 sparamuseca kernel: hub 2-1:1.0: 4 ports detected Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: new high-speed USB device number 3 using xhci-hcd Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: New USB device found, idVendor=0bda, idProduct=c820 Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: Product: 802.11ac NIC Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: Manufacturer: Realtek Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: SerialNumber: 123456 Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: Unsupported device Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: Unsupported device Mar 25 22:12:52 sparamuseca kernel: usb 1-1.4: Unsupported device Mar 25 22:12:52 sparamuseca initramfs: Booting Volumio for Odroid-N2 Mar 25 22:12:52 sparamuseca initramfs: This script mounts rootfs RO with an overlay RW layer. Mar 25 22:12:52 sparamuseca kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 25 22:12:52 sparamuseca kernel: fuse init (API version 7.26) Mar 25 22:12:52 sparamuseca initramfs: IMGPART=/dev/mmcblk0p2 Mar 25 22:12:52 sparamuseca initramfs: IMGFILE=/volumio_current.sqsh Mar 25 22:12:52 sparamuseca initramfs: DATAPART=/dev/mmcblk0p3 Mar 25 22:12:52 sparamuseca initramfs: BOOTPART=/dev/mmcblk0p1 Mar 25 22:12:52 sparamuseca initramfs: BOOTCONFIG=/boot/boot.ini Mar 25 22:12:52 sparamuseca initramfs: BOOTDELAY=5 Mar 25 22:12:52 sparamuseca kernel: EXT4-fs (mmcblk0p2): recovery complete Mar 25 22:12:52 sparamuseca kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Mar 25 22:12:52 sparamuseca initramfs: Checking for USB updates if you did not boot from USB... Mar 25 22:12:52 sparamuseca initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Mar 25 22:12:52 sparamuseca kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 25 22:12:52 sparamuseca kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 25 22:12:52 sparamuseca kernel: EXT4-fs (mmcblk0p3): recovery complete Mar 25 22:12:52 sparamuseca kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Mar 25 22:12:52 sparamuseca initramfs: With Option: Mar 25 22:12:52 sparamuseca initramfs: found fstab entry with UUID=FD06-2167 for /boot Mar 25 22:12:52 sparamuseca initramfs: VOLUMIO_VERSION="3.629" Mar 25 22:12:52 sparamuseca initramfs: Finish initramfs, continue booting Volumio Mar 25 22:12:52 sparamuseca systemd[1]: System time before build time, advancing clock. Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 10 Mar 25 22:12:52 sparamuseca kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Mar 25 22:12:52 sparamuseca kernel: cgroup: cgroup2: unknown option "nsdelegate" Mar 25 22:12:52 sparamuseca systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Mar 25 22:12:52 sparamuseca systemd[1]: Detected architecture arm64. Mar 25 22:12:52 sparamuseca systemd[1]: Set hostname to . Mar 25 22:12:52 sparamuseca systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Mar 25 22:12:52 sparamuseca systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Mar 25 22:12:52 sparamuseca systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 25 22:12:52 sparamuseca systemd[1]: Created slice User and Session Slice. Mar 25 22:12:52 sparamuseca systemd[1]: Started Forward Password Requests to Wall Directory Watch. Mar 25 22:12:52 sparamuseca systemd[1]: Reached target Slices. Mar 25 22:12:52 sparamuseca systemd[1]: Listening on fsck to fsckd communication Socket. Mar 25 22:12:52 sparamuseca kernel: RPC: Registered named UNIX socket transport module. Mar 25 22:12:52 sparamuseca kernel: RPC: Registered udp transport module. Mar 25 22:12:52 sparamuseca kernel: RPC: Registered tcp transport module. Mar 25 22:12:52 sparamuseca kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 25 22:12:52 sparamuseca systemd-journald[2242]: Journal started Mar 25 22:12:52 sparamuseca systemd-journald[2242]: Runtime journal (/run/log/journal/9b9a3f3f7d5269844d6e8b4d6204ef52) is 3.7M, max 30.0M, 26.2M free. Mar 25 22:12:52 sparamuseca fake-hwclock[2089]: Mon Mar 25 21:12:52 UTC 2024 Mar 25 22:12:52 sparamuseca systemd[1]: dynamicswap.service: Succeeded. Mar 25 22:12:52 sparamuseca systemd[1]: Mounted Kernel Debug File System. Mar 25 22:12:52 sparamuseca systemd[1]: Started Load Kernel Modules. Mar 25 22:12:52 sparamuseca systemd[1]: Started Create list of required static device nodes for the current kernel. Mar 25 22:12:52 sparamuseca systemd[1]: Starting Apply Kernel Variables... Mar 25 22:12:52 sparamuseca systemd[1]: Mounting FUSE Control File System... Mar 25 22:12:52 sparamuseca systemd[1]: Mounting Kernel Configuration File System... Mar 25 22:12:52 sparamuseca kernel: Removed cp15_barrier emulation handler Mar 25 22:12:52 sparamuseca kernel: Enabled cp15_barrier support Mar 25 22:12:52 sparamuseca systemd[1]: Starting Load/Save Random Seed... Mar 25 22:12:52 sparamuseca systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 25 22:12:52 sparamuseca systemd[1]: Starting Create System Users... Mar 25 22:12:52 sparamuseca systemd[1]: Started Apply Kernel Variables. Mar 25 22:12:52 sparamuseca systemd[1]: Mounted FUSE Control File System. Mar 25 22:12:52 sparamuseca systemd[1]: Mounted Kernel Configuration File System. Mar 25 22:12:52 sparamuseca systemd[1]: Started udev Coldplug all Devices. Mar 25 22:12:52 sparamuseca systemd[1]: Started Load/Save Random Seed. Mar 25 22:12:52 sparamuseca systemd[1]: Started Create System Users. Mar 25 22:12:52 sparamuseca systemd[1]: Starting Create Static Device Nodes in /dev... Mar 25 22:12:52 sparamuseca systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 25 22:12:52 sparamuseca systemd[1]: Started Create Static Device Nodes in /dev. Mar 25 22:12:52 sparamuseca systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 25 22:12:52 sparamuseca systemd[1]: Reached target Local File Systems (Pre). Mar 25 22:12:52 sparamuseca systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 25 22:12:52 sparamuseca systemd[1]: Mounting /tmp... Mar 25 22:12:52 sparamuseca systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 25 22:12:52 sparamuseca systemd[1]: Mounting /var/log... Mar 25 22:12:52 sparamuseca systemd[1]: Mounting /var/spool/cups... Mar 25 22:12:52 sparamuseca systemd[1]: Starting udev Kernel Device Manager... Mar 25 22:12:52 sparamuseca systemd[1]: Mounted /tmp. Mar 25 22:12:52 sparamuseca systemd[1]: Mounted /var/log. Mar 25 22:12:52 sparamuseca systemd-udevd[3164]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 25 22:12:52 sparamuseca systemd[1]: Mounted /var/spool/cups. Mar 25 22:12:52 sparamuseca systemd[1]: Started udev Kernel Device Manager. Mar 25 22:12:52 sparamuseca systemd[1]: Starting Show Plymouth Boot Screen... Mar 25 22:12:52 sparamuseca systemd[1]: Mounting /var/spool/cups/tmp... Mar 25 22:12:52 sparamuseca systemd[1]: Starting Flush Journal to Persistent Storage... Mar 25 22:12:52 sparamuseca systemd[1]: Mounted /var/spool/cups/tmp. Mar 25 22:12:52 sparamuseca systemd-udevd[3210]: Using default interface naming scheme 'v240'. Mar 25 22:12:52 sparamuseca systemd[1]: Received SIGRTMIN+20 from PID 3228 (plymouthd). Mar 25 22:12:52 sparamuseca systemd-udevd[3210]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 25 22:12:52 sparamuseca systemd-udevd[3210]: link_config: could not get ethtool features for eth0 Mar 25 22:12:52 sparamuseca systemd-udevd[3210]: Could not set offload features of eth0: Device or resource busy Mar 25 22:12:52 sparamuseca systemd[1]: Started Show Plymouth Boot Screen. Mar 25 22:12:52 sparamuseca systemd-journald[2242]: Runtime journal (/run/log/journal/9b9a3f3f7d5269844d6e8b4d6204ef52) is 3.7M, max 30.0M, 26.2M free. Mar 25 22:12:52 sparamuseca kernel: rtc rtc0: invalid alarm value: 2024-5-30 42:20:0 Mar 25 22:12:52 sparamuseca kernel: rtc-pcf8563 1-0051: rtc core: registered rtc-pcf8563 as rtc0 Mar 25 22:12:52 sparamuseca systemd-udevd[3206]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 25 22:12:52 sparamuseca kernel: Bluetooth: Core ver 2.22 Mar 25 22:12:52 sparamuseca kernel: Bluetooth: Starting self testing Mar 25 22:12:52 sparamuseca systemd[1]: Found device /dev/ttyS0. Mar 25 22:12:52 sparamuseca systemd[1]: Started Flush Journal to Persistent Storage. Mar 25 22:12:52 sparamuseca kernel: Bluetooth: ECDH test passed in 17302 usecs Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: ionvideo open Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close!!!! Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: ionvid: dbg: vidioc_close Mar 25 22:12:52 sparamuseca kernel: Bluetooth: SMP test passed in 18 usecs Mar 25 22:12:52 sparamuseca kernel: Bluetooth: Finished self testing Mar 25 22:12:52 sparamuseca kernel: NET: Registered protocol family 31 Mar 25 22:12:52 sparamuseca kernel: Bluetooth: HCI device and connection manager initialized Mar 25 22:12:52 sparamuseca kernel: Bluetooth: HCI socket layer initialized Mar 25 22:12:52 sparamuseca kernel: Bluetooth: L2CAP socket layer initialized Mar 25 22:12:52 sparamuseca kernel: Bluetooth: SCO socket layer initialized Mar 25 22:12:52 sparamuseca systemd[1]: Found device /dev/disk/by-uuid/FD06-2167. Mar 25 22:12:52 sparamuseca kernel: usbcore: registered new interface driver btusb Mar 25 22:12:52 sparamuseca kernel: Bluetooth: hci0: rtl: examining hci_ver=08 hci_rev=000c lmp_ver=08 lmp_subver=8821 Mar 25 22:12:52 sparamuseca kernel: Bluetooth: hci0: rtl: loading rtl_bt/rtl8821a_config.bin Mar 25 22:12:52 sparamuseca kernel: bluetooth hci0: Direct firmware load for rtl_bt/rtl8821a_config.bin failed with error -2 Mar 25 22:12:52 sparamuseca kernel: Bluetooth: hci0: Failed to load rtl_bt/rtl8821a_config.bin Mar 25 22:12:52 sparamuseca kernel: Bluetooth: hci0: rtl: loading rtl_bt/rtl8821a_fw.bin Mar 25 22:12:52 sparamuseca kernel: Bluetooth: hci0: rom_version status=0 version=1 Mar 25 22:12:52 sparamuseca kernel: Bluetooth: cfg_sz 0, total size 17428 Mar 25 22:12:52 sparamuseca systemd-udevd[3197]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 25 22:12:52 sparamuseca kernel: out of value, fixed it Mar 25 22:12:52 sparamuseca kernel: id=0 set inskew=0 Mar 25 22:12:52 sparamuseca kernel: AED req_sel0 module:TDMOUT_B Mar 25 22:12:52 sparamuseca kernel: spk_mute_set: mute flag = 0 Mar 25 22:12:53 sparamuseca systemd-udevd[3186]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 25 22:12:53 sparamuseca systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Starting File System Check on /dev/disk/by-uuid/FD06-2167... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Paths. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Local Encrypted Volumes. Mar 25 22:12:53 sparamuseca systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 25 22:12:53 sparamuseca systemd[1]: Started File System Check Daemon to report status. Mar 25 22:12:53 sparamuseca systemd[1]: Started Load/Save RF Kill Switch Status. Mar 25 22:12:53 sparamuseca systemd[1]: Found device /sys/subsystem/net/devices/eth0. Mar 25 22:12:53 sparamuseca systemd-fsck[3348]: fsck.fat 4.1 (2017-01-24) Mar 25 22:12:53 sparamuseca systemd-fsck[3348]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Mar 25 22:12:53 sparamuseca systemd-fsck[3348]: Automatically removing dirty bit. Mar 25 22:12:53 sparamuseca systemd-fsck[3348]: Performing changes. Mar 25 22:12:53 sparamuseca systemd-fsck[3348]: /dev/mmcblk0p1: 37 files, 8800/30651 clusters Mar 25 22:12:53 sparamuseca systemd[1]: Started File System Check on /dev/disk/by-uuid/FD06-2167. Mar 25 22:12:53 sparamuseca systemd[1]: Mounting /boot... Mar 25 22:12:53 sparamuseca systemd[1]: Mounted /boot. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Local File Systems. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 25 22:12:53 sparamuseca systemd[1]: Starting Preprocess NFS configuration... Mar 25 22:12:53 sparamuseca systemd[1]: Started ifup for eth0. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Raise network interfaces... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Create Volatile Files and Directories... Mar 25 22:12:53 sparamuseca systemd[1]: Received SIGRTMIN+20 from PID 3228 (plymouthd). Mar 25 22:12:53 sparamuseca systemd[1]: plymouth-read-write.service: Succeeded. Mar 25 22:12:53 sparamuseca systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 25 22:12:53 sparamuseca systemd[1]: nfs-config.service: Succeeded. Mar 25 22:12:53 sparamuseca systemd[1]: Started Preprocess NFS configuration. Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target NFS client services. Mar 25 22:12:53 sparamuseca systemd[1]: Started Create Volatile Files and Directories. Mar 25 22:12:53 sparamuseca ifup[3440]: command failed: No such device (-19) Mar 25 22:12:53 sparamuseca sh[3434]: command failed: No such device (-19) Mar 25 22:12:53 sparamuseca systemd[1]: Starting RPC bind portmap service... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 25 22:12:53 sparamuseca systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 25 22:12:53 sparamuseca systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target System Initialization. Mar 25 22:12:53 sparamuseca systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 25 22:12:53 sparamuseca systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 25 22:12:53 sparamuseca systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2024-04-17 10:20:32 CEST as it is in the future. Mar 25 22:12:53 sparamuseca systemd[1]: Started Daily apt download activities. Mar 25 22:12:53 sparamuseca systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2024-04-17 06:53:41 CEST as it is in the future. Mar 25 22:12:53 sparamuseca systemd[1]: Started Daily apt upgrade and clean activities. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Timers. Mar 25 22:12:53 sparamuseca systemd[1]: Listening on lircd.socket. Mar 25 22:12:53 sparamuseca systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 25 22:12:53 sparamuseca systemd[1]: Listening on triggerhappy.socket. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Sockets. Mar 25 22:12:53 sparamuseca systemd[1]: Started Volumio Splash. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Basic System. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Starting lircd(8) initialization helper tool... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 25 22:12:53 sparamuseca systemd[3596]: volumiosplash.service: Failed to execute command: No such file or directory Mar 25 22:12:53 sparamuseca systemd[3596]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Mar 25 22:12:53 sparamuseca systemd[1]: Started Volumio Iptables Module. Mar 25 22:12:53 sparamuseca systemd[1]: Started Volumio Log Rotation Service. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Wireless Services... Mar 25 22:12:53 sparamuseca systemd[1]: Started Manage Sound Card State (restore and store). Mar 25 22:12:53 sparamuseca kernel: usbcore: registered new interface driver rtl8821cu Mar 25 22:12:53 sparamuseca systemd-udevd[3252]: Using default interface naming scheme 'v240'. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Save/Restore Sound Card State... Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Successfully dropped root privileges. Mar 25 22:12:53 sparamuseca kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 25 22:12:53 sparamuseca systemd-udevd[3252]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: avahi-daemon 0.7 starting up. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Login Service... Mar 25 22:12:53 sparamuseca alsactl[3696]: alsactl 1.1.8 daemon started Mar 25 22:12:53 sparamuseca alsactl[3714]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 25 22:12:53 sparamuseca alsactl[3714]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 25 22:12:53 sparamuseca alsactl[3714]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 25 22:12:53 sparamuseca kernel: out of value, fixed it Mar 25 22:12:53 sparamuseca kernel: id=0 set inskew=0 Mar 25 22:12:53 sparamuseca kernel: AED req_sel0 module:TDMOUT_B Mar 25 22:12:53 sparamuseca kernel: spk_mute_set: mute flag = 0 Mar 25 22:12:53 sparamuseca alsactl[3696]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 25 22:12:53 sparamuseca alsactl[3696]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 25 22:12:53 sparamuseca alsactl[3696]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 25 22:12:53 sparamuseca systemd[1]: Started UPnP Renderer front-end to MPD. Mar 25 22:12:53 sparamuseca kernel: out of value, fixed it Mar 25 22:12:53 sparamuseca kernel: id=0 set inskew=0 Mar 25 22:12:53 sparamuseca kernel: AED req_sel0 module:TDMOUT_B Mar 25 22:12:53 sparamuseca kernel: spk_mute_set: mute flag = 0 Mar 25 22:12:53 sparamuseca systemd[1]: Starting Bluetooth service... Mar 25 22:12:53 sparamuseca kernel: nf_tables: (c) 2007-2009 Patrick McHardy Mar 25 22:12:53 sparamuseca systemd[1]: Started D-Bus System Message Bus. Mar 25 22:12:53 sparamuseca kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Mar 25 22:12:53 sparamuseca systemd[1]: Starting WPA supplicant... Mar 25 22:12:53 sparamuseca systemd[1]: Starting triggerhappy global hotkey daemon... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Starting DHCP Client Daemon... Mar 25 22:12:53 sparamuseca systemd[1]: Started volumio-remote-updater.service. Mar 25 22:12:53 sparamuseca systemd[1]: Started RPC bind portmap service. Mar 25 22:12:53 sparamuseca systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Mar 25 22:12:53 sparamuseca systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Mar 25 22:12:53 sparamuseca thd[3845]: Unable to parse trigger line: Mar 25 22:12:53 sparamuseca thd[3845]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 25 22:12:53 sparamuseca thd[3845]: Unable to parse trigger line: Mar 25 22:12:53 sparamuseca thd[3845]: Found socket passed from systemd Mar 25 22:12:53 sparamuseca systemd-logind[3723]: Watching system buttons on /dev/input/event0 (gpio_keypad) Mar 25 22:12:53 sparamuseca systemd-logind[3723]: Watching system buttons on /dev/input/event1 (cec_input) Mar 25 22:12:53 sparamuseca systemd[1]: Started triggerhappy global hotkey daemon. Mar 25 22:12:53 sparamuseca systemd-logind[3723]: New seat seat0. Mar 25 22:12:53 sparamuseca systemd[1]: Started Save/Restore Sound Card State. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Sound Card. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Remote File Systems (Pre). Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Remote File Systems. Mar 25 22:12:53 sparamuseca systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 25 22:12:53 sparamuseca systemd[1]: Reached target RPC Port Mapper. Mar 25 22:12:53 sparamuseca kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Successfully called chroot(). Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Successfully dropped remaining capabilities. Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Loading service file /services/volumio.service. Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Network interface enumeration completed. Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Server startup complete. Host name is sparamuseca.local. Local service cookie is 1566959761. Mar 25 22:12:53 sparamuseca avahi-daemon[3597]: Service "sparamuseca" (/services/volumio.service) successfully established. Mar 25 22:12:53 sparamuseca systemd[1]: Started Login Service. Mar 25 22:12:53 sparamuseca systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 25 22:12:53 sparamuseca bluetoothd[3800]: Bluetooth daemon 5.50 Mar 25 22:12:53 sparamuseca bluetoothd[3800]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 25 22:12:53 sparamuseca systemd[1]: Started Bluetooth service. Mar 25 22:12:53 sparamuseca bluetoothd[3800]: Starting SDP server Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Bluetooth. Mar 25 22:12:53 sparamuseca bluetoothd[3800]: Excluding (cli) sap Mar 25 22:12:53 sparamuseca dhcpcd[3859]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Mar 25 22:12:53 sparamuseca kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 25 22:12:53 sparamuseca kernel: Bluetooth: BNEP filters: protocol multicast Mar 25 22:12:53 sparamuseca kernel: Bluetooth: BNEP socket layer initialized Mar 25 22:12:53 sparamuseca bluetoothd[3800]: Bluetooth management interface 1.14 initialized Mar 25 22:12:53 sparamuseca dbus-daemon[3814]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.3' (uid=0 pid=3800 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Mar 25 22:12:53 sparamuseca wpa_supplicant[3830]: Successfully initialized wpa_supplicant Mar 25 22:12:53 sparamuseca systemd[1]: Found device 802.11ac_NIC. Mar 25 22:12:53 sparamuseca systemd[1]: Started WPA supplicant. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Hostname Service... Mar 25 22:12:53 sparamuseca systemd[1]: Started ifup for wlan0. Mar 25 22:12:53 sparamuseca kernel: meson6-dwmac ff3f0000.ethernet eth0: fail to init PTP. Mar 25 22:12:53 sparamuseca sh[3434]: eth0: waiting for carrier Mar 25 22:12:53 sparamuseca dhcpcd[3591]: eth0: waiting for carrier Mar 25 22:12:53 sparamuseca sh[3434]: eth0: carrier acquired Mar 25 22:12:53 sparamuseca dhcpcd[3591]: eth0: carrier acquired Mar 25 22:12:53 sparamuseca volumio-remote-updater[3875]: Error: No active session Mar 25 22:12:53 sparamuseca dhcpcd[3859]: no interfaces have a carrier Mar 25 22:12:53 sparamuseca dhcpcd[3859]: no interfaces have a carrier Mar 25 22:12:53 sparamuseca dhcpcd[3859]: forked to background, child pid 4089 Mar 25 22:12:53 sparamuseca dhcpcd[3859]: forked to background, child pid 4089 Mar 25 22:12:53 sparamuseca systemd[1]: Started DHCP Client Daemon. Mar 25 22:12:53 sparamuseca sh[3434]: DUID 00:01:00:01:29:97:ac:48:00:1e:06:43:69:5d Mar 25 22:12:53 sparamuseca sh[3434]: eth0: IAID 06:43:69:5d Mar 25 22:12:53 sparamuseca dhcpcd[3591]: DUID 00:01:00:01:29:97:ac:48:00:1e:06:43:69:5d Mar 25 22:12:53 sparamuseca dhcpcd[3591]: eth0: IAID 06:43:69:5d Mar 25 22:12:53 sparamuseca dhcpcd[4089]: eth0: waiting for carrier Mar 25 22:12:53 sparamuseca sh[3434]: eth0: adding address fe80::8b0b:5274:988a:f60e Mar 25 22:12:53 sparamuseca dhcpcd[3591]: eth0: adding address fe80::8b0b:5274:988a:f60e Mar 25 22:12:53 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:12:53] [info] asio async_connect error: system:111 (Connection refused) Mar 25 22:12:53 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:12:53] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 25 22:12:53 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:12:53] [error] handle_connect error: Underlying Transport Error Mar 25 22:12:53 sparamuseca volumio[3778]: Could not open config: /tmp/upmpdcli.conf Mar 25 22:12:53 sparamuseca systemd[1]: Started Raise network interfaces. Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Network. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Permit User Sessions... Mar 25 22:12:53 sparamuseca systemd[1]: Starting Network Time Service... Mar 25 22:12:53 sparamuseca systemd[1]: Starting OpenBSD Secure Shell server... Mar 25 22:12:53 sparamuseca systemd[1]: Reached target Network is Online. Mar 25 22:12:53 sparamuseca systemd[1]: Starting LSB: Brings up/down network automatically... Mar 25 22:12:53 sparamuseca systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 25 22:12:53 sparamuseca systemd[1]: Starting Samba NMB Daemon... Mar 25 22:12:53 sparamuseca systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 25 22:12:53 sparamuseca systemd[1]: Starting /etc/rc.local Compatibility... Mar 25 22:12:53 sparamuseca systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 25 22:12:53 sparamuseca systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 25 22:12:53 sparamuseca systemd[1]: Started Permit User Sessions. Mar 25 22:12:53 sparamuseca lircd-setup[3599]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Mar 25 22:12:53 sparamuseca lircd-setup[3599]: parser = configparser.SafeConfigParser() Mar 25 22:12:53 sparamuseca ntpd[4111]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 25 22:12:53 sparamuseca ntpd[4111]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 25 22:12:53 sparamuseca systemd[1]: Started Network Time Service. Mar 25 22:12:53 sparamuseca ntpd[4251]: proto: precision = 0.458 usec (-21) Mar 25 22:12:53 sparamuseca ntpd[4251]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 25 22:12:53 sparamuseca ntpd[4251]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 25 22:12:53 sparamuseca ntpd[4251]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 454 days ago Mar 25 22:12:53 sparamuseca ntpd[4251]: Listen and drop on 0 v6wildcard [::]:123 Mar 25 22:12:53 sparamuseca ntpd[4251]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 25 22:12:53 sparamuseca systemd[1]: lircd-setup.service: Succeeded. Mar 25 22:12:53 sparamuseca systemd[1]: Started lircd(8) initialization helper tool. Mar 25 22:12:53 sparamuseca systemd[1]: Started /etc/rc.local Compatibility. Mar 25 22:12:53 sparamuseca dbus-daemon[3814]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 25 22:12:53 sparamuseca systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 25 22:12:53 sparamuseca systemd[1]: Starting Hold until boot process finishes up... Mar 25 22:12:53 sparamuseca loadcpufreq[3935]: Loading cpufreq kernel modules...done (none). Mar 25 22:12:53 sparamuseca systemd[1]: Starting Flexible IR remote input/output application support... Mar 25 22:12:53 sparamuseca systemd[1]: plymouth-start.service: Succeeded. Mar 25 22:12:53 sparamuseca systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Info: lircd: Opening log, level: Info Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Version: lircd 0.10.1 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: System info: Linux sparamuseca 4.9.241+ #7 SMP PREEMPT Sat Apr 9 15:06:53 CEST 2022 aarch64 GNU/Linux Mar 25 22:12:53 sparamuseca systemd[1]: plymouth-quit.service: Succeeded. Mar 25 22:12:53 sparamuseca systemd[1]: Started Terminate Plymouth Boot Screen. Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Info: Initial device: auto Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Info: Initial device: auto Mar 25 22:12:53 sparamuseca lircd[4325]: lircd-0.10.1[4325]: Info: lircd: Opening log, level: Info Mar 25 22:12:53 sparamuseca lircd[4325]: lircd-0.10.1[4325]: Notice: Using systemd fd Mar 25 22:12:53 sparamuseca lircd[4325]: lircd-0.10.1[4325]: Warning: Running as root Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: driver: devinput Mar 25 22:12:53 sparamuseca lircd[4325]: lircd-0.10.1[4325]: Info: Using remote: odroid. Mar 25 22:12:53 sparamuseca lircd[4325]: lircd-0.10.1[4325]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: output: /var/run/lirc/lircd Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: nodaemon: 1 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: logfile: syslog Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: immediate-init: 0 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: permission: 666 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: driver-options: Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: listen: 0 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: connect: (null) Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: userelease: 0 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: effective_user: (null) Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: release_suffix: _EVUP Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: allow_simulate: 0 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: repeat_max: 600 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: configfile: /etc/lirc/lircd.conf Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Options: dynamic_codes: (null) Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Current driver: devinput Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Driver API version: 4 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Driver version: 0.10.0 Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Info: lircd: Opening log, level: Info Mar 25 22:12:53 sparamuseca systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: Using systemd fd Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Warning: Running as root Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Info: Using remote: odroid. Mar 25 22:12:53 sparamuseca lircd-0.10.1[4325]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Mar 25 22:12:53 sparamuseca nmbd[4232]: [2024/03/25 22:12:53.959022, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 25 22:12:53 sparamuseca nmbd[4232]: started asyncdns process 4371 Mar 25 22:12:53 sparamuseca systemd[1]: Started Hold until boot process finishes up. Mar 25 22:12:53 sparamuseca systemd[1]: Started Flexible IR remote input/output application support. Mar 25 22:12:54 sparamuseca systemd[1]: iptables.service: Succeeded. Mar 25 22:12:54 sparamuseca systemd[1]: Received SIGRTMIN+21 from PID 3228 (n/a). Mar 25 22:12:54 sparamuseca systemd[1]: Received SIGRTMIN+21 from PID 3228 (n/a). Mar 25 22:12:54 sparamuseca systemd[1]: Started Hostname Service. Mar 25 22:12:54 sparamuseca systemd[1]: Started Serial Getty on ttyS0. Mar 25 22:12:54 sparamuseca systemd[1]: Started Getty on tty1. Mar 25 22:12:54 sparamuseca systemd[1]: Reached target Login Prompts. Mar 25 22:12:54 sparamuseca systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 25 22:12:54 sparamuseca ifplugd(eth0)[4406]: ifplugd 0.28 initializing. Mar 25 22:12:54 sparamuseca cpufrequtils[4379]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...CPU4...CPU5...done. Mar 25 22:12:54 sparamuseca systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 25 22:12:54 sparamuseca haveged[3560]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Mar 25 22:12:54 sparamuseca haveged[3560]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Mar 25 22:12:54 sparamuseca haveged[3560]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99815 Mar 25 22:12:54 sparamuseca haveged[3560]: haveged: fills: 0, generated: 0 Mar 25 22:12:54 sparamuseca wireless.js[3626]: Cleaning previous... Mar 25 22:12:54 sparamuseca sh[3434]: ipv6_addaddr1: Permission denied Mar 25 22:12:54 sparamuseca sh[3434]: eth0: carrier lost Mar 25 22:12:54 sparamuseca dhcpcd[3591]: ipv6_addaddr1: Permission denied Mar 25 22:12:54 sparamuseca ntpd[4251]: Listen normally on 2 lo 127.0.0.1:123 Mar 25 22:12:54 sparamuseca dhcpcd[3591]: eth0: carrier lost Mar 25 22:12:54 sparamuseca ntpd[4251]: Listening on routing socket on fd #19 for interface updates Mar 25 22:12:54 sparamuseca ifplugd(eth0)[4406]: Using interface eth0/00:1E:06:43:69:5D with driver (version: Jan_2016) Mar 25 22:12:54 sparamuseca ifplugd(eth0)[4406]: Using detection mode: SIOCETHTOOL Mar 25 22:12:54 sparamuseca ntpd[4251]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 25 22:12:54 sparamuseca ntpd[4251]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 25 22:12:54 sparamuseca ifplugd(eth0)[4406]: Initialization complete, link beat not detected. Mar 25 22:12:54 sparamuseca nmbd[4232]: [2024/03/25 22:12:54.496690, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 25 22:12:54 sparamuseca nmbd[4232]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 25 22:12:54 sparamuseca nmbd[4232]: [2024/03/25 22:12:54.496774, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 25 22:12:54 sparamuseca nmbd[4232]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 25 22:12:54 sparamuseca dhcpcd[4089]: wlan0: waiting for carrier Mar 25 22:12:54 sparamuseca dhcpcd[4089]: eth0: carrier acquired Mar 25 22:12:54 sparamuseca dhcpcd[4089]: DUID 00:01:00:01:29:97:ac:48:00:1e:06:43:69:5d Mar 25 22:12:54 sparamuseca dhcpcd[4089]: eth0: IAID 06:43:69:5d Mar 25 22:12:54 sparamuseca dhcpcd[4089]: eth0: adding address fe80::8b0b:5274:988a:f60e Mar 25 22:12:54 sparamuseca dhcpcd[4089]: ipv6_addaddr1: Permission denied Mar 25 22:12:54 sparamuseca dhcpcd[4089]: eth0: carrier lost Mar 25 22:12:54 sparamuseca sudo[4460]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 25 22:12:54 sparamuseca sudo[4460]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:54 sparamuseca sh[4037]: sending commands to master dhcpcd process Mar 25 22:12:54 sparamuseca dhcpcd[4494]: sending commands to master dhcpcd process Mar 25 22:12:54 sparamuseca sshd[4475]: Server listening on 0.0.0.0 port 22. Mar 25 22:12:54 sparamuseca systemd[1]: Started OpenBSD Secure Shell server. Mar 25 22:12:54 sparamuseca sshd[4475]: Server listening on :: port 22. Mar 25 22:12:54 sparamuseca dhcpcd[4089]: control command: /sbin/dhcpcd wlan0 Mar 25 22:12:54 sparamuseca sudo[4460]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:54 sparamuseca sudo[4501]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 25 22:12:54 sparamuseca sudo[4501]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:54 sparamuseca sudo[4501]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:54 sparamuseca ifplugd[4155]: Network Interface Plugging Daemon...start eth0...done. Mar 25 22:12:54 sparamuseca systemd[1]: Started LSB: Brings up/down network automatically. Mar 25 22:12:54 sparamuseca sh[4037]: wlan0=wlan0 Mar 25 22:12:54 sparamuseca wireless.js[3626]: Stopped aP Mar 25 22:12:54 sparamuseca sudo[4535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 25 22:12:54 sparamuseca sudo[4535]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:54 sparamuseca sudo[4535]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:54 sparamuseca sudo[4537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 25 22:12:54 sparamuseca sudo[4537]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:54 sparamuseca sudo[4537]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:54 sparamuseca ntpd[4251]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 25 22:12:54 sparamuseca sudo[4544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 25 22:12:54 sparamuseca sudo[4544]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:54 sparamuseca sudo[4544]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:54 sparamuseca sudo[4546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 25 22:12:54 sparamuseca sudo[4546]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:55 sparamuseca kernel: Bluetooth: hci0 command 0xfc20 tx timeout Mar 25 22:12:55 sparamuseca kernel: vout: aml_tvout_mode_work: monitor_timeout Mar 25 22:12:55 sparamuseca ntpd[4251]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 25 22:12:56 sparamuseca ntpd[4251]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 25 22:12:57 sparamuseca ntpd[4251]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 25 22:12:58 sparamuseca systemd[1]: systemd-rfkill.service: Succeeded. Mar 25 22:12:58 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:12:58] [info] asio async_connect error: system:111 (Connection refused) Mar 25 22:12:58 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:12:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 25 22:12:58 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:12:58] [error] handle_connect error: Underlying Transport Error Mar 25 22:12:58 sparamuseca sudo[4546]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:58 sparamuseca wireless.js[3626]: Start wireless flow Mar 25 22:12:58 sparamuseca wireless.js[3626]: Stopped hotspot (if there).. Mar 25 22:12:58 sparamuseca sudo[4551]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 25 22:12:58 sparamuseca sudo[4551]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:58 sparamuseca sudo[4551]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:58 sparamuseca sudo[4553]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 25 22:12:58 sparamuseca sudo[4553]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:58 sparamuseca sudo[4553]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:58 sparamuseca wireless.js[3626]: DHCP IP Mar 25 22:12:58 sparamuseca wireless.js[3626]: Start ap Mar 25 22:12:58 sparamuseca wpa_supplicant[4555]: Successfully initialized wpa_supplicant Mar 25 22:12:58 sparamuseca sudo[4556]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 25 22:12:58 sparamuseca sudo[4556]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:58 sparamuseca dhcpcd[4557]: sending commands to master dhcpcd process Mar 25 22:12:58 sparamuseca dhcpcd[4089]: control command: /sbin/dhcpcd Mar 25 22:12:58 sparamuseca sudo[4556]: pam_unix(sudo:session): session closed for user root Mar 25 22:12:59 sparamuseca wireless.js[3626]: trying... Mar 25 22:12:59 sparamuseca sudo[4594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:12:59 sparamuseca sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:12:59 sparamuseca sudo[4594]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:00 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:00 sparamuseca sudo[4597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:00 sparamuseca sudo[4597]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:00 sparamuseca sudo[4597]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:01 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:01 sparamuseca sudo[4600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:01 sparamuseca sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:01 sparamuseca sudo[4600]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:02 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:02 sparamuseca sudo[4603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:02 sparamuseca sudo[4603]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:02 sparamuseca sudo[4603]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:03 sparamuseca wpa_supplicant[4559]: wlan0: Trying to associate with 7e:83:c2:ce:b0:aa (SSID='FSM' freq=5240 MHz) Mar 25 22:13:03 sparamuseca wpa_supplicant[4559]: wlan0: Associated with 7e:83:c2:ce:b0:aa Mar 25 22:13:03 sparamuseca wpa_supplicant[4559]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 25 22:13:03 sparamuseca wpa_supplicant[4559]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=NL Mar 25 22:13:03 sparamuseca wpa_supplicant[4559]: wlan0: WPA: Key negotiation completed with 7e:83:c2:ce:b0:aa [PTK=CCMP GTK=CCMP] Mar 25 22:13:03 sparamuseca wpa_supplicant[4559]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7e:83:c2:ce:b0:aa completed [id=0 id_str=] Mar 25 22:13:03 sparamuseca dhcpcd[4089]: wlan0: carrier acquired Mar 25 22:13:03 sparamuseca dhcpcd[4089]: wlan0: IAID a9:33:e6:9d Mar 25 22:13:03 sparamuseca dhcpcd[4089]: wlan0: adding address fe80::113a:e43a:d39f:ce0b Mar 25 22:13:03 sparamuseca dhcpcd[4089]: ipv6_addaddr1: Permission denied Mar 25 22:13:03 sparamuseca bluetoothd[3800]: No Bluetooth address for index 0 Mar 25 22:13:03 sparamuseca kernel: Bluetooth: hci0: download fw command failed (-110) Mar 25 22:13:03 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:03 sparamuseca sudo[4610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:03 sparamuseca sudo[4610]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:03 sparamuseca sudo[4610]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:04 sparamuseca dhcpcd[4089]: wlan0: soliciting an IPv6 router Mar 25 22:13:04 sparamuseca dhcpcd[4089]: wlan0: rebinding lease of 192.168.50.11 Mar 25 22:13:04 sparamuseca dhcpcd[4089]: wlan0: probing address 192.168.50.11/24 Mar 25 22:13:04 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:04 sparamuseca sudo[4614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:04 sparamuseca sudo[4614]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:04 sparamuseca sudo[4614]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:05 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:05 sparamuseca sudo[4617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:05 sparamuseca sudo[4617]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:05 sparamuseca sudo[4617]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:06 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:13:06] [info] asio async_connect error: system:111 (Connection refused) Mar 25 22:13:06 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:13:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 25 22:13:06 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:13:06] [error] handle_connect error: Underlying Transport Error Mar 25 22:13:06 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:06 sparamuseca sudo[4620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:06 sparamuseca sudo[4620]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:06 sparamuseca sudo[4620]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:07 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:07 sparamuseca sudo[4623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:07 sparamuseca sudo[4623]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:07 sparamuseca sudo[4623]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:08 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:08 sparamuseca sudo[4626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:08 sparamuseca sudo[4626]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:08 sparamuseca sudo[4626]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:09 sparamuseca dhcpcd[4089]: wlan0: leased 192.168.50.11 for 1800 seconds Mar 25 22:13:09 sparamuseca avahi-daemon[3597]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.11. Mar 25 22:13:09 sparamuseca avahi-daemon[3597]: New relevant interface wlan0.IPv4 for mDNS. Mar 25 22:13:09 sparamuseca avahi-daemon[3597]: Registering new address record for 192.168.50.11 on wlan0.IPv4. Mar 25 22:13:09 sparamuseca dhcpcd[4089]: wlan0: adding route to 192.168.50.0/24 Mar 25 22:13:09 sparamuseca dhcpcd[4089]: wlan0: adding default route via 192.168.50.1 Mar 25 22:13:09 sparamuseca ntpd[4251]: ntpd exiting on signal 15 (Terminated) Mar 25 22:13:09 sparamuseca systemd[1]: Stopping Network Time Service... Mar 25 22:13:09 sparamuseca systemd[1]: ntp.service: Succeeded. Mar 25 22:13:09 sparamuseca systemd[1]: Stopped Network Time Service. Mar 25 22:13:09 sparamuseca systemd[1]: Starting Network Time Service... Mar 25 22:13:09 sparamuseca ntpd[4665]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 25 22:13:09 sparamuseca ntpd[4665]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 25 22:13:09 sparamuseca systemd[1]: Started Network Time Service. Mar 25 22:13:09 sparamuseca ntpd[4697]: proto: precision = 0.791 usec (-20) Mar 25 22:13:09 sparamuseca ntpd[4697]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 25 22:13:09 sparamuseca ntpd[4697]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 25 22:13:09 sparamuseca ntpd[4697]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 454 days ago Mar 25 22:13:09 sparamuseca ntpd[4697]: Listen and drop on 0 v6wildcard [::]:123 Mar 25 22:13:09 sparamuseca ntpd[4697]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 25 22:13:09 sparamuseca ntpd[4697]: Listen normally on 2 lo 127.0.0.1:123 Mar 25 22:13:09 sparamuseca ntpd[4697]: Listen normally on 3 wlan0 192.168.50.11:123 Mar 25 22:13:09 sparamuseca ntpd[4697]: Listening on routing socket on fd #20 for interface updates Mar 25 22:13:09 sparamuseca ntpd[4697]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 25 22:13:09 sparamuseca ntpd[4697]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 25 22:13:09 sparamuseca wireless.js[3626]: trying... Mar 25 22:13:09 sparamuseca sudo[4700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 25 22:13:09 sparamuseca sudo[4700]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:09 sparamuseca sudo[4700]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:09 sparamuseca wireless.js[3626]: Connected to: ----FSM Mar 25 22:13:09 sparamuseca wireless.js[3626]: ---- Mar 25 22:13:09 sparamuseca sudo[4703]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 22:13:09 sparamuseca sudo[4703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:09 sparamuseca sudo[4703]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:09 sparamuseca wireless.js[3626]: ... joined AP, wlan0 IPv4 is 192.168.50.11, ipV6 is undefined Mar 25 22:13:09 sparamuseca wireless.js[3626]: It's done! AP Mar 25 22:13:09 sparamuseca iwconfig[4707]: Error for wireless request "Set Power Management" (8B2C) : Mar 25 22:13:09 sparamuseca iwconfig[4707]: SET failed on device wlan0 ; Operation not permitted. Mar 25 22:13:09 sparamuseca systemd[1]: Started Wireless Services. Mar 25 22:13:09 sparamuseca systemd[1]: Started Volumio Backend Module. Mar 25 22:13:09 sparamuseca systemd[1]: Started Volumio Cpu Tweaker. Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: Setting RT Priority for mpd Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: pid 35's current scheduling policy: SCHED_OTHER Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: pid 35's current scheduling priority: 0 Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: Setting MPD Affinity Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: pid 3's current affinity mask: 1 Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 25 22:13:10 sparamuseca volumio-cpu-tweak[4709]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 25 22:13:10 sparamuseca systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 25 22:13:10 sparamuseca ntpd[4697]: Soliciting pool server 193.123.37.231 Mar 25 22:13:11 sparamuseca volumio[4708]: info: ------------------------------------------- Mar 25 22:13:11 sparamuseca volumio[4708]: info: ----- Volumio3 ---- Mar 25 22:13:11 sparamuseca volumio[4708]: info: ------------------------------------------- Mar 25 22:13:11 sparamuseca volumio[4708]: info: ----- System startup ---- Mar 25 22:13:11 sparamuseca volumio[4708]: info: ------------------------------------------- Mar 25 22:13:11 sparamuseca volumio[4708]: info: MYVOLUMIO Environment detected Mar 25 22:13:11 sparamuseca volumio[4708]: info: Plugin folders cleanup Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning into folder /volumio/app/plugins/ Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category audio_interface Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category miscellanea Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category music_service Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category plugins.json Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category system_controller Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category user_interface Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning into folder /data/plugins/ Mar 25 22:13:11 sparamuseca volumio[4708]: info: Scanning category music_service Mar 25 22:13:11 sparamuseca volumio[4708]: info: Plugin folders cleanup completed Mar 25 22:13:11 sparamuseca volumio[4708]: info: ------------------------------------------- Mar 25 22:13:11 sparamuseca volumio[4708]: info: ----- Core plugins startup ---- Mar 25 22:13:11 sparamuseca volumio[4708]: info: ------------------------------------------- Mar 25 22:13:11 sparamuseca volumio[4708]: info: Loading plugins from folder /volumio/app/plugins/ Mar 25 22:13:11 sparamuseca volumio[4708]: info: Adding plugin upnp to MyMusic Plugins Mar 25 22:13:11 sparamuseca volumio[4708]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 25 22:13:11 sparamuseca volumio[4708]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 25 22:13:11 sparamuseca volumio[4708]: info: Loading plugins from folder /data/plugins/ Mar 25 22:13:11 sparamuseca volumio[4708]: info: Loading plugin "system"... Mar 25 22:13:11 sparamuseca volumio[4708]: info: Loading plugin "appearance"... Mar 25 22:13:11 sparamuseca ntpd[4697]: Soliciting pool server 158.101.216.150 Mar 25 22:13:11 sparamuseca ntpd[4697]: Soliciting pool server 195.123.221.21 Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "network"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Refreshing Cached IP Addresses Mar 25 22:13:12 sparamuseca sudo[4785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 22:13:12 sparamuseca sudo[4785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:12 sparamuseca sudo[4785]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:12 sparamuseca sudo[4788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 22:13:12 sparamuseca sudo[4788]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "services"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "alsa_controller"... Mar 25 22:13:12 sparamuseca sudo[4788]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:12 sparamuseca sudo[4792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 25 22:13:12 sparamuseca sudo[4792]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:12 sparamuseca volumio[4708]: warn: Unable to locate the audio output device ADI-2 DAC (52041186). Please configure a valid output device. Mar 25 22:13:12 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "wizard"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "networkfs"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Starting Udev Watcher for removable devices Mar 25 22:13:12 sparamuseca sudo[4798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=iK0olohwaey1ish6phaepieVoo9ib4wo,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //sklad.casa.nando.sh/music /mnt/NAS/sklad Mar 25 22:13:12 sparamuseca sudo[4798]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:12 sparamuseca volumio[4708]: info: Ignoring mount for partition: boot Mar 25 22:13:12 sparamuseca volumio[4708]: info: Ignoring mount for partition: volumio Mar 25 22:13:12 sparamuseca volumio[4708]: info: Ignoring mount for partition: volumio_data Mar 25 22:13:12 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "volumio_command_line_client"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Plugin upnp is not enabled Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "my_music"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "mpd"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Plugin upnp_browser is not enabled Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "alarm-clock"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Plugin airplay_emulation is not enabled Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "last_100"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "webradio"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "i2s_dacs"... Mar 25 22:13:12 sparamuseca volumio[4708]: info: I2S DAC not set, start Auto-detection Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "volumiodiscovery"... Mar 25 22:13:12 sparamuseca volumio[4708]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 25 22:13:12 sparamuseca volumio[4708]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 22:13:12 sparamuseca volumio[4708]: *** WARNING *** For more information see Mar 25 22:13:12 sparamuseca volumio[4708]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 25 22:13:12 sparamuseca volumio[4708]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 22:13:12 sparamuseca node[4708]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 25 22:13:12 sparamuseca volumio[4708]: *** WARNING *** For more information see Mar 25 22:13:12 sparamuseca node[4708]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 22:13:12 sparamuseca node[4708]: *** WARNING *** For more information see Mar 25 22:13:12 sparamuseca node[4708]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 25 22:13:12 sparamuseca node[4708]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 25 22:13:12 sparamuseca node[4708]: *** WARNING *** For more information see Mar 25 22:13:12 sparamuseca volumio[4708]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 25 22:13:12 sparamuseca volumio[4708]: info: Discovery: Started advertising with name: sparamuseca Mar 25 22:13:12 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 25 22:13:12 sparamuseca volumio[4708]: info: Loading plugin "spop"... Mar 25 22:13:12 sparamuseca ntpd[4697]: Soliciting pool server 45.138.55.60 Mar 25 22:13:12 sparamuseca ntpd[4697]: Soliciting pool server 162.159.200.1 Mar 25 22:13:13 sparamuseca volumio[4708]: info: Loading plugin "youtube2"... Mar 25 22:13:14 sparamuseca systemd[1]: Started Samba NMB Daemon. Mar 25 22:13:14 sparamuseca nmbd[4232]: [2024/03/25 22:13:14.508054, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 25 22:13:14 sparamuseca nmbd[4232]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 25 22:13:14 sparamuseca systemd[1]: Starting Samba Winbind Daemon... Mar 25 22:13:14 sparamuseca winbindd[4804]: [2024/03/25 22:13:14.652835, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 25 22:13:14 sparamuseca winbindd[4804]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 25 22:13:14 sparamuseca winbindd[4804]: [2024/03/25 22:13:14.663458, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 25 22:13:14 sparamuseca winbindd[4804]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 25 22:13:14 sparamuseca systemd[1]: Started Samba Winbind Daemon. Mar 25 22:13:14 sparamuseca systemd[1]: Starting Samba SMB Daemon... Mar 25 22:13:14 sparamuseca winbindd[4804]: [2024/03/25 22:13:14.875587, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Mar 25 22:13:14 sparamuseca winbindd[4804]: res_names->count = 3, expected 4 Mar 25 22:13:14 sparamuseca smbd[4850]: [2024/03/25 22:13:14.889953, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 25 22:13:14 sparamuseca smbd[4850]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 25 22:13:14 sparamuseca systemd[1]: Started Samba SMB Daemon. Mar 25 22:13:14 sparamuseca systemd[1]: Reached target Multi-User System. Mar 25 22:13:14 sparamuseca systemd[1]: Starting Disable Odroid blue led... Mar 25 22:13:14 sparamuseca systemd[1]: Reached target Graphical Interface. Mar 25 22:13:14 sparamuseca systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 25 22:13:14 sparamuseca systemd[1]: odroid-led.service: Succeeded. Mar 25 22:13:14 sparamuseca systemd[1]: Started Disable Odroid blue led. Mar 25 22:13:14 sparamuseca systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 25 22:13:14 sparamuseca systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 25 22:13:14 sparamuseca systemd[1]: Startup finished in 7.374s (kernel) + 23.444s (userspace) = 30.818s. Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "outputs"... Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "albumart"... Mar 25 22:13:15 sparamuseca volumio[4708]: info: Plugin example_plugin is not enabled Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "inputs"... Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "updater_comm"... Mar 25 22:13:15 sparamuseca volumio[4708]: info: Plugin mpdemulation is not enabled Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "rest_api"... Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "websocket"... Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "80s80s"... Mar 25 22:13:15 sparamuseca volumio[4708]: Forking 5 albumart workers Mar 25 22:13:15 sparamuseca volumio[4708]: info: Applying required configuration parameters for plugin 80s80s Mar 25 22:13:15 sparamuseca volumio[4708]: info: [1711401195755] [80s80s] API delay: 30 Mar 25 22:13:15 sparamuseca volumio[4708]: info: Loading plugin "motherearthradio"... Mar 25 22:13:15 sparamuseca volumio[4708]: Starting albumart workers Mar 25 22:13:15 sparamuseca volumio[4708]: Starting albumart workers Mar 25 22:13:15 sparamuseca volumio[4708]: Starting albumart workers Mar 25 22:13:15 sparamuseca volumio[4708]: Starting albumart workers Mar 25 22:13:16 sparamuseca volumio[4708]: Starting albumart workers Mar 25 22:13:16 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:13:16] [connect] Successful connection Mar 25 22:13:16 sparamuseca volumio[4708]: info: Applying required configuration parameters for plugin motherearthradio Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196166] [MotherEarth] API delay: 5 Mar 25 22:13:16 sparamuseca volumio[4708]: info: Loading plugin "personal_radio"... Mar 25 22:13:16 sparamuseca kernel: FS-Cache: Loaded Mar 25 22:13:16 sparamuseca ntpd[4697]: Soliciting pool server 213.136.0.252 Mar 25 22:13:16 sparamuseca ntpd[4697]: Soliciting pool server 158.101.213.248 Mar 25 22:13:16 sparamuseca sudo[4792]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:16 sparamuseca kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 25 22:13:16 sparamuseca kernel: Key type cifs.spnego registered Mar 25 22:13:16 sparamuseca kernel: Key type cifs.idmap registered Mar 25 22:13:16 sparamuseca volumio[4708]: info: ControllerPersonalRadio::constructor Mar 25 22:13:16 sparamuseca volumio[4708]: info: Loading plugin "radio_paradise"... Mar 25 22:13:16 sparamuseca volumio[4708]: info: Applying required configuration parameters for plugin radio_paradise Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196609] [RadioParadise] API delay: 5 Mar 25 22:13:16 sparamuseca volumio[4708]: info: Loading i18n strings for locale en Mar 25 22:13:16 sparamuseca volumio[4708]: Updating browse sources language Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::initPlayerControls Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: Express server listening on port 3000 Mar 25 22:13:16 sparamuseca volumio[4708]: [Metrics] WebUI: 6s 30.43ms Mar 25 22:13:16 sparamuseca sudo[4798]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreStateMachine::resetVolumioState Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreStateMachine::getcurrentVolume Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioRetrievevolume Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreStateMachine::pushState Mar 25 22:13:16 sparamuseca volumio[4708]: info: CorePlayQueue::getTrack 0 Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioPushState Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 22:13:16 sparamuseca volumio[4708]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 25 22:13:16 sparamuseca volumio[4708]: info: Completed loading Core Plugins Mar 25 22:13:16 sparamuseca volumio[4708]: info: Preparing to generate the ALSA configuration file Mar 25 22:13:16 sparamuseca volumio-remote-updater[3875]: [2024-03-25 22:13:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711401196 101 Mar 25 22:13:16 sparamuseca volumio[4708]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 25 22:13:16 sparamuseca ntpd[4697]: Soliciting pool server 185.224.145.68 Mar 25 22:13:16 sparamuseca ntpd[4697]: Soliciting pool server 5.39.184.12 Mar 25 22:13:16 sparamuseca ntpd[4697]: Soliciting pool server 78.142.193.131 Mar 25 22:13:16 sparamuseca volumio[4708]: info: Reloading queue from file Mar 25 22:13:16 sparamuseca volumio[4708]: info: Asound.conf file unchanged, so no further update is needed Mar 25 22:13:16 sparamuseca volumio[4708]: info: Output device has changed, restarting MPD Mar 25 22:13:16 sparamuseca sudo[5029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 22:13:16 sparamuseca sudo[5029]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:16 sparamuseca volumio[4708]: info: ___________ START PLUGINS ___________ Mar 25 22:13:16 sparamuseca sudo[5029]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:16 sparamuseca volumio[4708]: info: ControllerMpd::onStart: Initializing MPD Mar 25 22:13:16 sparamuseca volumio[4708]: info: Creating MPD Configuration file Mar 25 22:13:16 sparamuseca sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 22:13:16 sparamuseca sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:16 sparamuseca sudo[5035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 25 22:13:16 sparamuseca sudo[5035]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196908] CoreMusicLibrary::Adding element Last_100 Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196910] CoreMusicLibrary::Adding element Webradio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca systemd[1]: Listening on mpd.socket. Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 22:13:16 sparamuseca systemd[1]: Starting Music Player Daemon... Mar 25 22:13:16 sparamuseca sudo[5035]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:16 sparamuseca sudo[5038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 25 22:13:16 sparamuseca sudo[5038]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:16 sparamuseca volumio[4708]: info: Creating Spotify config file Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:16 sparamuseca systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 25 22:13:16 sparamuseca systemd[1]: mpd.service: Succeeded. Mar 25 22:13:16 sparamuseca systemd[1]: Stopped Music Player Daemon. Mar 25 22:13:16 sparamuseca systemd[1]: Starting Music Player Daemon... Mar 25 22:13:16 sparamuseca volumio[4708]: info: [youtube2-auth] Starting auth flow... Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196973] CoreMusicLibrary::Adding element YouTube2 Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source YouTube2 Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196977] CoreMusicLibrary::Adding element 80s80s Radio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source YouTube2 Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source 80s80s Radio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196981] CoreMusicLibrary::Adding element Mother Earth Radio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source YouTube2 Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source 80s80s Radio Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source Mother Earth Radio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196990] CoreMusicLibrary::Adding element Personal Radio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source YouTube2 Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source 80s80s Radio Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source Mother Earth Radio Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source Personal Radio Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 25 22:13:16 sparamuseca volumio[4708]: info: [1711401196993] CoreMusicLibrary::Adding element Radio Paradise Mar 25 22:13:16 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source YouTube2 Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source 80s80s Radio Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source Mother Earth Radio Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source Personal Radio Mar 25 22:13:16 sparamuseca volumio[4708]: Cannot find translation for source Radio Paradise Mar 25 22:13:16 sparamuseca volumio[4708]: info: Volumio Calling Home Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreStateMachine::setRepeat false single undefined Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreStateMachine::pushState Mar 25 22:13:17 sparamuseca volumio[4708]: info: CorePlayQueue::getTrack 0 Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioPushState Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreStateMachine::setRandom false Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreStateMachine::pushState Mar 25 22:13:17 sparamuseca volumio[4708]: info: CorePlayQueue::getTrack 0 Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioPushState Mar 25 22:13:17 sparamuseca volumio[4708]: info: MPD Permissions set Mar 25 22:13:17 sparamuseca volumio[4708]: info: MPD Permissions set Mar 25 22:13:17 sparamuseca volumio[4708]: info: Spotify config file written Mar 25 22:13:17 sparamuseca sudo[5077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 25 22:13:17 sparamuseca sudo[5077]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 22:13:17 sparamuseca volumio[4708]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 25 22:13:17 sparamuseca systemd[1]: Started go-librespot Daemon. Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca sudo[5077]: pam_unix(sudo:session): session closed for user root Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca go-librespot[5084]: Librespot-go daemon starting... Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 22:13:17 sparamuseca volumio[4708]: info: Discovery: adding 8c98625d-ed6b-4629-8bb3-e2660aff379f Mar 25 22:13:17 sparamuseca volumio[4708]: info: Discovery: Found device sparamuseca Mar 25 22:13:17 sparamuseca volumio[4708]: info: CoreCommandRouter::volumioGetState Mar 25 22:13:17 sparamuseca volumio[4708]: info: CorePlayQueue::getTrack 0 Mar 25 22:13:17 sparamuseca volumio[4708]: info: Volumio called home Mar 25 22:13:17 sparamuseca volumio[4708]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 22:13:17 sparamuseca volumio[4708]: ReferenceError: deferred is not defined Mar 25 22:13:17 sparamuseca volumio[4708]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Mar 25 22:13:17 sparamuseca volumio[4708]: at ClientRequest.emit (events.js:400:28) Mar 25 22:13:17 sparamuseca volumio[4708]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Mar 25 22:13:17 sparamuseca volumio[4708]: at TLSSocket.emit (events.js:400:28) Mar 25 22:13:17 sparamuseca volumio[4708]: at emitErrorNT (internal/streams/destroy.js:106:8) Mar 25 22:13:17 sparamuseca volumio[4708]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Mar 25 22:13:17 sparamuseca volumio[4708]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 25 22:13:17 sparamuseca volumio[4708]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 22:13:17 sparamuseca go-librespot[5084]: time="2024-03-25T22:13:17+01:00" level=info msg="generated new device id: a4128c7f8034c3f0dd8a28a154e4e23035c7c880" Mar 25 22:13:17 sparamuseca go-librespot[5084]: time="2024-03-25T22:13:17+01:00" level=debug msg="stored credentials found for 11170617236" Mar 25 22:13:17 sparamuseca go-librespot[5084]: time="2024-03-25T22:13:17+01:00" level=debug msg="obtained new client token: AABQW2Ygpl+HNTeD+QICmSEqeYeXyb256cBdn5ibfN2Mx2EKiKNG9iQussEVlTfAF9v95mmK0XgQHEZtAZR5MtKILwgTDJpc3zWnKa++ZgfIQdakOa5fzqcnPTkAiGsFfvUw4W69gyKDptRQ1vCs/3Hqv85fZgWASNYkYNExedMH8+0RK1utKDHrR7sw/jC00XJ6K3mJ30KoiEOS1YpqlcaquoXyl8VzRr3Euoi0WlCZoG2Dfm6oHXgk+oP4/QHH" Mar 25 22:13:17 sparamuseca sudo[5133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-25 22:12 Mar 25 22:13:17 sparamuseca sudo[5133]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 24 Feb 2024 09:07:43 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="odroidn2" VOLUMIO_DEVICENAME="Odroid-N2" VOLUMIO_HASH="d092cca031b797e808566131fd2637e0"