-- Logs begin at Mon 2025-09-01 18:44:22 CEST, end at Mon 2025-09-01 18:45:29 CEST. -- Sep 01 18:44:22 volumion2 kernel: Booting Linux on physical CPU 0x0 Sep 01 18:44:22 volumion2 kernel: Linux version 4.9.241+ (odroidn2@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #8 SMP PREEMPT Sat Jan 21 21:01:33 CET 2023 Sep 01 18:44:22 volumion2 kernel: Boot CPU: AArch64 Processor [410fd034] Sep 01 18:44:22 volumion2 kernel: Machine model: Hardkernel ODROID-N2Plus Sep 01 18:44:22 volumion2 kernel: efi: Getting EFI parameters from FDT: Sep 01 18:44:22 volumion2 kernel: efi: UEFI not found. Sep 01 18:44:22 volumion2 kernel: __reserved_mem_alloc_size, start:0x000000007f800000, end:0x0000000080000000, len:8 MiB Sep 01 18:44:22 volumion2 kernel: 7f800000 - 80000000, 8192 KB, linux,meson-fb Sep 01 18:44:22 volumion2 kernel: e5800000 - ed800000, 131072 KB, linux,ion-dev Sep 01 18:44:22 volumion2 kernel: e3000000 - e5800000, 40960 KB, linux,di_cma Sep 01 18:44:22 volumion2 kernel: Reserved memory: created DMA memory pool at 0x00000000e3000000, size 0 MiB Sep 01 18:44:22 volumion2 kernel: e3000000 - e3000000, 0 KB, linux,ppmgr Sep 01 18:44:22 volumion2 kernel: cfc00000 - e3000000, 315392 KB, linux,codec_mm_cma Sep 01 18:44:22 volumion2 kernel: cfc00000 - cfc00000, 0 KB, linux,codec_mm_reserved Sep 01 18:44:22 volumion2 kernel: __reserved_mem_alloc_size, start:0x0000000005000000, end:0x0000000005400000, len:4 MiB Sep 01 18:44:22 volumion2 kernel: 05000000 - 05400000, 4096 KB, linux,secmon Sep 01 18:44:22 volumion2 kernel: cma: Reserved 8 MiB at 0x00000000cf400000 Sep 01 18:44:22 volumion2 kernel: On node 0 totalpages: 972800 Sep 01 18:44:22 volumion2 kernel: DMA zone: 15200 pages used for memmap Sep 01 18:44:22 volumion2 kernel: DMA zone: 0 pages reserved Sep 01 18:44:22 volumion2 kernel: DMA zone: 972800 pages, LIFO batch:31 Sep 01 18:44:22 volumion2 kernel: psci: probing for conduit method from DT. Sep 01 18:44:22 volumion2 kernel: psci: PSCIv1.0 detected in firmware. Sep 01 18:44:22 volumion2 kernel: psci: Using standard PSCI v0.2 function IDs Sep 01 18:44:22 volumion2 kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 01 18:44:22 volumion2 kernel: psci: SMC Calling Convention v1.1 Sep 01 18:44:22 volumion2 kernel: percpu: Embedded 21 pages/cpu s47960 r8192 d29864 u86016 Sep 01 18:44:22 volumion2 kernel: pcpu-alloc: s47960 r8192 d29864 u86016 alloc=21*4096 Sep 01 18:44:22 volumion2 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 Sep 01 18:44:22 volumion2 kernel: Detected VIPT I-cache on CPU0 Sep 01 18:44:22 volumion2 kernel: CPU features: enabling workaround for ARM erratum 845719 Sep 01 18:44:22 volumion2 kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 957600 Sep 01 18:44:22 volumion2 kernel: Kernel command line: imgpart=UUID=146f039b-c799-470d-a14a-76eefade5157 bootpart=UUID=5671-75AA datapart=UUID=6139f5e4-f6bd-40f6-ab4b-10d8068848ae imgfile=/volumio_current.sqsh uuidconfig=boot.ini quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear hwdevice=Odroid-N2 bootdelay=5 gpiopower=479 rootwait rw console=ttyS0,115200n8 no_console_suspend fsck.repair=yes net.ifnames=0 elevator=noop hdmimode=2160p60hz cvbsmode=576cvbs max_freq_a53=1908 max_freq_a73=2208 maxcpus=6 voutmode=hdmi disablehpd=false cvbscable= overscan=100 monitor_onoff=false logo=osd0,loaded hdmitx=cec3f sdrmode=auto Sep 01 18:44:22 volumion2 kernel: gpio-keypad: gpiopower_setup gpiopower : 479 Sep 01 18:44:22 volumion2 kernel: vout: get hdmimode: 2160p60hz Sep 01 18:44:22 volumion2 kernel: vout: get cvbsmode: 576cvbs Sep 01 18:44:22 volumion2 kernel: max cpufreq of cluster0 : 1908000kHz Sep 01 18:44:22 volumion2 kernel: max cpufreq of cluster1 : 2208000kHz Sep 01 18:44:22 volumion2 kernel: hdmitx: voutmode : 1 Sep 01 18:44:22 volumion2 kernel: fb: osd0 Sep 01 18:44:22 volumion2 kernel: fb: loaded Sep 01 18:44:22 volumion2 kernel: hdmitx: HDMI hdmi_cec_func_config:0x3f Sep 01 18:44:22 volumion2 kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Sep 01 18:44:22 volumion2 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) Sep 01 18:44:22 volumion2 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) Sep 01 18:44:22 volumion2 kernel: Memory: 3276176K/3891200K available (11964K kernel code, 1816K rwdata, 5136K rodata, 4992K init, 1421K bss, 107120K reserved, 507904K cma-reserved) Sep 01 18:44:22 volumion2 kernel: Virtual kernel memory layout: Sep 01 18:44:22 volumion2 kernel: modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) Sep 01 18:44:22 volumion2 kernel: vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB) Sep 01 18:44:22 volumion2 kernel: .text : 0xffffff8009080000 - 0xffffff8009c30000 ( 11968 KB) Sep 01 18:44:22 volumion2 kernel: .rodata : 0xffffff8009c30000 - 0xffffff800a140000 ( 5184 KB) Sep 01 18:44:22 volumion2 kernel: .init : 0xffffff800a140000 - 0xffffff800a620000 ( 4992 KB) Sep 01 18:44:22 volumion2 kernel: .data : 0xffffff800a620000 - 0xffffff800a7e6200 ( 1817 KB) Sep 01 18:44:22 volumion2 kernel: .bss : 0xffffff800a7e6200 - 0xffffff800a9498bc ( 1422 KB) Sep 01 18:44:22 volumion2 kernel: fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB) Sep 01 18:44:22 volumion2 kernel: PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB) Sep 01 18:44:22 volumion2 kernel: vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum) Sep 01 18:44:22 volumion2 kernel: 0xffffffbf00000000 - 0xffffffbf03b60000 ( 59 MB actual) Sep 01 18:44:22 volumion2 kernel: memory : 0xffffffc000000000 - 0xffffffc0ed800000 ( 3800 MB) Sep 01 18:44:22 volumion2 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 01 18:44:22 volumion2 kernel: Preemptible hierarchical RCU implementation. Sep 01 18:44:22 volumion2 kernel: Build-time adjustment of leaf fanout to 64. Sep 01 18:44:22 volumion2 kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=6. Sep 01 18:44:22 volumion2 kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=6 Sep 01 18:44:22 volumion2 kernel: NR_IRQS:64 nr_irqs:64 0 Sep 01 18:44:22 volumion2 kernel: GPIO-INTC: support to detect double-edge trigger signal Sep 01 18:44:22 volumion2 kernel: g12a_aoclkc_init: register ao clk ok! Sep 01 18:44:22 volumion2 kernel: Meson chip version = RevC (29:C - 40:0) Sep 01 18:44:22 volumion2 kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Sep 01 18:44:22 volumion2 kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Sep 01 18:44:22 volumion2 kernel: meson_g12a_gpu_init: register meson gpu clk Sep 01 18:44:22 volumion2 kernel: meson_g12a_media_init: register meson media clk Sep 01 18:44:22 volumion2 kernel: meson_g12a_misc_init: register amlogic g12a misc clks Sep 01 18:44:22 volumion2 kernel: meson_g12a_misc_init: done. Sep 01 18:44:22 volumion2 kernel: g12a_clkc_init initialization complete Sep 01 18:44:22 volumion2 kernel: arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). Sep 01 18:44:22 volumion2 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Sep 01 18:44:22 volumion2 kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Sep 01 18:44:22 volumion2 kernel: meson_bc_timer: mclk->mux_reg =ffffff800800c190,mclk->reg =ffffff800800e194 Sep 01 18:44:22 volumion2 kernel: Console: colour dummy device 80x25 Sep 01 18:44:22 volumion2 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Sep 01 18:44:22 volumion2 kernel: pid_max: default: 32768 minimum: 301 Sep 01 18:44:22 volumion2 kernel: Security Framework initialized Sep 01 18:44:22 volumion2 kernel: SELinux: Initializing. Sep 01 18:44:22 volumion2 kernel: SELinux: Starting in permissive mode Sep 01 18:44:22 volumion2 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) Sep 01 18:44:22 volumion2 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) Sep 01 18:44:22 volumion2 kernel: ftrace: allocating 39807 entries in 156 pages Sep 01 18:44:22 volumion2 kernel: sched-energy: Sched-energy-costs installed from DT Sep 01 18:44:22 volumion2 kernel: CPU0: update cpu_capacity 631 Sep 01 18:44:22 volumion2 kernel: ASID allocator initialised with 65536 entries Sep 01 18:44:22 volumion2 kernel: secmon: clear_range:5100000 200000 Sep 01 18:44:22 volumion2 kernel: EFI services will not be available. Sep 01 18:44:22 volumion2 kernel: Detected VIPT I-cache on CPU1 Sep 01 18:44:22 volumion2 kernel: CPU1: update cpu_capacity 631 Sep 01 18:44:22 volumion2 kernel: CPU1: Booted secondary processor [410fd034] Sep 01 18:44:22 volumion2 kernel: Detected VIPT I-cache on CPU2 Sep 01 18:44:22 volumion2 kernel: CPU2: update cpu_capacity 1192 Sep 01 18:44:22 volumion2 kernel: CPU2: Booted secondary processor [410fd092] Sep 01 18:44:22 volumion2 kernel: Detected VIPT I-cache on CPU3 Sep 01 18:44:22 volumion2 kernel: CPU3: update cpu_capacity 1192 Sep 01 18:44:22 volumion2 kernel: CPU3: Booted secondary processor [410fd092] Sep 01 18:44:22 volumion2 kernel: Detected VIPT I-cache on CPU4 Sep 01 18:44:22 volumion2 kernel: CPU4: update cpu_capacity 1192 Sep 01 18:44:22 volumion2 kernel: CPU4: Booted secondary processor [410fd092] Sep 01 18:44:22 volumion2 kernel: Detected VIPT I-cache on CPU5 Sep 01 18:44:22 volumion2 kernel: CPU5: update cpu_capacity 1192 Sep 01 18:44:22 volumion2 kernel: CPU5: Booted secondary processor [410fd092] Sep 01 18:44:22 volumion2 kernel: Brought up 6 CPUs Sep 01 18:44:22 volumion2 kernel: SMP: Total of 6 processors activated. Sep 01 18:44:22 volumion2 kernel: CPU features: detected feature: 32-bit EL0 Support Sep 01 18:44:22 volumion2 kernel: CPU: All CPU(s) started at EL2 Sep 01 18:44:22 volumion2 kernel: alternatives: patching kernel code Sep 01 18:44:22 volumion2 kernel: devtmpfs: initialized Sep 01 18:44:22 volumion2 kernel: DMI not present or invalid. Sep 01 18:44:22 volumion2 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Sep 01 18:44:22 volumion2 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes) Sep 01 18:44:22 volumion2 kernel: pinctrl core: initialized pinctrl subsystem Sep 01 18:44:22 volumion2 kernel: NET: Registered protocol family 16 Sep 01 18:44:22 volumion2 kernel: schedtune: init normalization constants... Sep 01 18:44:22 volumion2 kernel: schedtune: CLUSTER[0-1] min_pwr: 0 max_pwr: 42 Sep 01 18:44:22 volumion2 kernel: schedtune: CPU[0] min_pwr: 0 max_pwr: 279 Sep 01 18:44:22 volumion2 kernel: schedtune: CPU[1] min_pwr: 0 max_pwr: 279 Sep 01 18:44:22 volumion2 kernel: schedtune: CLUSTER[2-5] min_pwr: 0 max_pwr: 110 Sep 01 18:44:22 volumion2 kernel: schedtune: CPU[2] min_pwr: 0 max_pwr: 1048 Sep 01 18:44:22 volumion2 kernel: schedtune: CPU[3] min_pwr: 0 max_pwr: 1048 Sep 01 18:44:22 volumion2 kernel: schedtune: CPU[4] min_pwr: 0 max_pwr: 1048 Sep 01 18:44:22 volumion2 kernel: schedtune: CPU[5] min_pwr: 0 max_pwr: 1048 Sep 01 18:44:22 volumion2 kernel: schedtune: SYSTEM min_pwr: 0 max_pwr: 4902 Sep 01 18:44:22 volumion2 kernel: schedtune: using normalization constants mul: 2882587190 sh1: 1 sh2: 12 Sep 01 18:44:22 volumion2 kernel: schedtune: verify normalization constants... Sep 01 18:44:22 volumion2 kernel: schedtune: max_pwr/2^0: 4902 => norm_pwr: 1024 Sep 01 18:44:22 volumion2 kernel: schedtune: max_pwr/2^1: 2451 => norm_pwr: 512 Sep 01 18:44:22 volumion2 kernel: schedtune: max_pwr/2^2: 1225 => norm_pwr: 255 Sep 01 18:44:22 volumion2 kernel: schedtune: max_pwr/2^3: 612 => norm_pwr: 127 Sep 01 18:44:22 volumion2 kernel: schedtune: max_pwr/2^4: 306 => norm_pwr: 63 Sep 01 18:44:22 volumion2 kernel: schedtune: max_pwr/2^5: 153 => norm_pwr: 31 Sep 01 18:44:22 volumion2 kernel: schedtune: configured to support global boosting only Sep 01 18:44:22 volumion2 kernel: cpuidle: using governor menu Sep 01 18:44:22 volumion2 kernel: register canvas platform driver Sep 01 18:44:22 volumion2 kernel: register rdma platform driver Sep 01 18:44:22 volumion2 kernel: vdso: 2 pages (1 code @ ffffff8009c37000, 1 data @ ffffff800a625000) Sep 01 18:44:22 volumion2 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 01 18:44:22 volumion2 kernel: DMA: preallocated 2048 KiB pool for atomic allocations Sep 01 18:44:22 volumion2 kernel: clkmsr: clkmsr: driver init Sep 01 18:44:22 volumion2 kernel: codec_mm_module_init Sep 01 18:44:22 volumion2 kernel: media_configs_system_init Sep 01 18:44:22 volumion2 kernel: aml_iomap: amlogic iomap probe done Sep 01 18:44:22 volumion2 kernel: vpu: driver version: v20190329(8-g12b) Sep 01 18:44:22 volumion2 kernel: vpu: load vpu_clk: 666666667Hz(7) Sep 01 18:44:22 volumion2 kernel: vpu: clktree_init Sep 01 18:44:22 volumion2 kernel: vpu: vpu_probe OK Sep 01 18:44:22 volumion2 kernel: clkmsr: msr_clk_reg0=ffffff800823a004,msr_clk_reg2=ffffff800823c00c Sep 01 18:44:22 volumion2 kernel: clkmsr ffd18004.meson_clk_msr: failed to get msr ring reg0 Sep 01 18:44:22 volumion2 kernel: audio_clocks: audio_clocks_probe done Sep 01 18:44:22 volumion2 kernel: aml_vdac_config_probe: cpu_id:4, name:meson-g12ab-vdac Sep 01 18:44:22 volumion2 kernel: aml_vdac_probe: ok Sep 01 18:44:22 volumion2 kernel: canvas_probe reg=00000000ff638000,size=2000 Sep 01 18:44:22 volumion2 kernel: canvas maped reg_base =ffffff8008268000 Sep 01 18:44:22 volumion2 kernel: rdma_probe,cpu_type:0, ver:0, len:8 Sep 01 18:44:22 volumion2 kernel: rdma_register, rdma_table_addr ffffff8008277000 rdma_table_addr_phy cf600000 reg_buf ffffffc0ca2b8000 Sep 01 18:44:22 volumion2 kernel: rdma_register success, handle 1 table_size 32768 Sep 01 18:44:22 volumion2 kernel: set_rdma_handle video rdma handle = 1. Sep 01 18:44:22 volumion2 kernel: classs created ok Sep 01 18:44:22 volumion2 kernel: classs file created ok Sep 01 18:44:22 volumion2 kernel: aml_snd_reg_map[0], reg:ff640000, size:2000 Sep 01 18:44:22 volumion2 kernel: aml_snd_reg_map[1], reg:ff642000, size:2000 Sep 01 18:44:22 volumion2 kernel: aml_snd_reg_map[2], reg:ff64a000, size:2000 Sep 01 18:44:22 volumion2 kernel: aml_snd_reg_map[3], reg:ff656000, size:1800 Sep 01 18:44:22 volumion2 kernel: aml_snd_reg_map[4], reg:ffd01000, size:1000 Sep 01 18:44:22 volumion2 kernel: amlogic auge_snd_iomap probe done Sep 01 18:44:22 volumion2 kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Sep 01 18:44:22 volumion2 kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Sep 01 18:44:22 volumion2 kernel: codec_mm_probe ok Sep 01 18:44:22 volumion2 kernel: cvbs_out: cvbsout_probe, cpu_id:5,name:meson-g12b-cvbsout Sep 01 18:44:22 volumion2 kernel: cvbs_out: clk path:0 Sep 01 18:44:22 volumion2 kernel: cvbs_out: cvbs trimming.1.v5: 0xa0, 0x0 Sep 01 18:44:22 volumion2 kernel: vout: vout1: register server: cvbs_vout_server Sep 01 18:44:22 volumion2 kernel: cvbs_out: register cvbs module server ok Sep 01 18:44:22 volumion2 kernel: vout: vout2: register server: cvbs_vout2_server Sep 01 18:44:22 volumion2 kernel: cvbs_out: register cvbs module vout2 server ok Sep 01 18:44:22 volumion2 kernel: cvbs_out: chrdev devno 264241152 for disp Sep 01 18:44:22 volumion2 kernel: cvbs_out: create cdev cvbs Sep 01 18:44:22 volumion2 kernel: cvbs_out: cvbsout_probe OK Sep 01 18:44:22 volumion2 kernel: SCSI subsystem initialized Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver usbfs Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver hub Sep 01 18:44:22 volumion2 kernel: usbcore: registered new device driver usb Sep 01 18:44:22 volumion2 kernel: media: Linux media interface: v0.10 Sep 01 18:44:22 volumion2 kernel: Linux video capture interface: v2.00 Sep 01 18:44:22 volumion2 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 01 18:44:22 volumion2 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 01 18:44:22 volumion2 kernel: PTP clock support registered Sep 01 18:44:22 volumion2 kernel: dmi: Firmware registration failed. Sep 01 18:44:22 volumion2 kernel: secmon: reserve_mem_size:0x300000 Sep 01 18:44:22 volumion2 kernel: secmon secmon: assigned reserved memory node linux,secmon Sep 01 18:44:22 volumion2 kernel: secmon: get page:ffffffbf00140000, 5000 Sep 01 18:44:22 volumion2 kernel: secmon: share in base: 0xffffffc0050fe000, share out base: 0xffffffc0050ff000 Sep 01 18:44:22 volumion2 kernel: secmon: phy_in_base: 0x50fe000, phy_out_base: 0x50ff000 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: amhdmitx_probe start Sep 01 18:44:22 volumion2 kernel: hdmitx: system: Ver: 20190815 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: hdmitx_device.chip_type : 11 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: not find match pwr-ctl Sep 01 18:44:22 volumion2 kernel: hdmitx: system: not find drm_amhdmitx Sep 01 18:44:22 volumion2 kernel: hdmitx: system: hpd irq = 41 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: hdcp22_tx_skp failed to probe Sep 01 18:44:22 volumion2 kernel: hdmitx: system: hdcp22_tx_esm failed to probe Sep 01 18:44:22 volumion2 kernel: vout: vout1: register server: hdmitx_vout_server Sep 01 18:44:22 volumion2 kernel: vout: vout2: register server: hdmitx_vout2_server Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xff634400 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xff900000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xff800000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xff63c000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xff608000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xff600000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx20: Mapped PHY: 0xffe01000 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: alread display in uboot 0x10 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: avmute set to 1 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: fmt_attr 444,8bit Sep 01 18:44:22 volumion2 kernel: hdmitx: system: fmt_attr 444,8bit Sep 01 18:44:22 volumion2 kernel: hdmitx: system: amhdmitx_probe end Sep 01 18:44:22 volumion2 kernel: vout: create vout attribute OK Sep 01 18:44:22 volumion2 kernel: vout: vout_fops_create OK Sep 01 18:44:22 volumion2 kernel: vout: vout1: register server: nulldisp_vout_server Sep 01 18:44:22 volumion2 kernel: hdmitx: system: irq 80000002 0 Sep 01 18:44:22 volumion2 kernel: vout: tvout monitor interval:500(ms), timeout cnt:20 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx_set_current_vmode[3877] Sep 01 18:44:22 volumion2 kernel: hdmitx: system: recalc before 2160p60hz 60 1 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: recalc after 2160p60hz 2997 50 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: get current mode: 2160p60hz Sep 01 18:44:22 volumion2 kernel: hdmitx: system: update rx hdr info 0 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: update physcial size: 16 9 Sep 01 18:44:22 volumion2 kernel: hdmitx: video: already init VIC = 0 Now VIC = 97 Sep 01 18:44:22 volumion2 kernel: hdmitx: video: rx edid only support RGB format Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: div40: 1 Sep 01 18:44:22 volumion2 kernel: hdmitx: ddc w1b error 0x54 0x20 0x03 Sep 01 18:44:22 volumion2 kernel: hdmitx: frac_rate = 1 Sep 01 18:44:22 volumion2 kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Sep 01 18:44:22 volumion2 kernel: hdmitx: HPLLv1: 0xdb3a04f7 Sep 01 18:44:22 volumion2 kernel: hdmitx: config HPLL done Sep 01 18:44:22 volumion2 kernel: hdmitx: set_hpll_od3_clk_div[643] div = 6 Sep 01 18:44:22 volumion2 kernel: hdmitx: j = 9 vid_clk_div = 1 Sep 01 18:44:22 volumion2 kernel: hdmitx: set enc for VIC: 97 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: irq 80000001 0 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: config hdmitx IP vic = 97 cd:4 cs: 0 Sep 01 18:44:22 volumion2 kernel: hdmitx: video: Sink is HDMI device Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: set audio Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 01 18:44:22 volumion2 kernel: hdmitx: fs = 0, cd = 4, tmds_clk = 593407 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: aud_n_para = 5824 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: set channel status Sep 01 18:44:22 volumion2 kernel: vout: init mode 2160p60hz set ok Sep 01 18:44:22 volumion2 kernel: vout: aml_tvout_mode_monitor Sep 01 18:44:22 volumion2 kernel: vout: aml_vout_probe OK Sep 01 18:44:22 volumion2 kernel: chip type:0x29 Sep 01 18:44:22 volumion2 kernel: MEMORY:[0+ed800000] Sep 01 18:44:22 volumion2 kernel: ramdump_probe, storage device:data Sep 01 18:44:22 volumion2 kernel: NO valid ramdump args:0 0 Sep 01 18:44:22 volumion2 kernel: ramdump_probe, set sticky to 8ed8 Sep 01 18:44:22 volumion2 kernel: Advanced Linux Sound Architecture Driver Initialized. Sep 01 18:44:22 volumion2 kernel: NetLabel: Initializing Sep 01 18:44:22 volumion2 kernel: NetLabel: domain hash size = 128 Sep 01 18:44:22 volumion2 kernel: NetLabel: protocols = UNLABELED CIPSOv4 Sep 01 18:44:22 volumion2 kernel: NetLabel: unlabeled traffic allowed by default Sep 01 18:44:22 volumion2 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000104]: 0x00000001 Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000180]: 0x000000ff Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000181]: 0x000000ff Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000182]: 0x00000003 Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000183]: 0x00000007 Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000184]: 0x0000003f Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000185]: 0x00000002 Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000187]: 0x000000ff Sep 01 18:44:22 volumion2 kernel: hdmitx: [0x10000188]: 0x00000003 Sep 01 18:44:22 volumion2 kernel: VFS: Disk quotas dquot_6.6.0 Sep 01 18:44:22 volumion2 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 01 18:44:22 volumion2 kernel: NET: Registered protocol family 2 Sep 01 18:44:22 volumion2 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes) Sep 01 18:44:22 volumion2 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes) Sep 01 18:44:22 volumion2 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 01 18:44:22 volumion2 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes) Sep 01 18:44:22 volumion2 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) Sep 01 18:44:22 volumion2 kernel: NET: Registered protocol family 1 Sep 01 18:44:22 volumion2 kernel: PCI: CLS 0 bytes, default 64 Sep 01 18:44:22 volumion2 kernel: Unpacking initramfs... Sep 01 18:44:22 volumion2 kernel: hdmitx: system: plugin Sep 01 18:44:22 volumion2 kernel: Freeing initrd memory: 13028K Sep 01 18:44:22 volumion2 kernel: hw perfevents: clusterb_enabled = 1 Sep 01 18:44:22 volumion2 kernel: hw perfevents: cpumasks 0x3, 0x3c Sep 01 18:44:22 volumion2 kernel: hw perfevents: cluster A irq = 18 Sep 01 18:44:22 volumion2 kernel: hw perfevents: cluster B irq = 19 Sep 01 18:44:22 volumion2 kernel: hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available Sep 01 18:44:22 volumion2 kernel: audit: initializing netlink subsys (disabled) Sep 01 18:44:22 volumion2 kernel: audit: type=2000 audit(1.020:1): initialized Sep 01 18:44:22 volumion2 kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Sep 01 18:44:22 volumion2 kernel: Registering sdcardfs 0.1 Sep 01 18:44:22 volumion2 kernel: SELinux: Registering netfilter hooks Sep 01 18:44:22 volumion2 kernel: NET: Registered protocol family 38 Sep 01 18:44:22 volumion2 kernel: Key type asymmetric registered Sep 01 18:44:22 volumion2 kernel: Asymmetric key parser 'x509' registered Sep 01 18:44:22 volumion2 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) Sep 01 18:44:22 volumion2 kernel: io scheduler noop registered (default) Sep 01 18:44:22 volumion2 kernel: io scheduler deadline registered Sep 01 18:44:22 volumion2 kernel: io scheduler cfq registered Sep 01 18:44:22 volumion2 kernel: pwm-gpio pwmgpio: 1 gpio pwms loaded Sep 01 18:44:22 volumion2 kernel: meson-pwm ff802000.pwm: pwm pinmux : can't get pinctrl Sep 01 18:44:22 volumion2 kernel: meson-pwm ffd1b000.pwm: pwm pinmux : can't get pinctrl Sep 01 18:44:22 volumion2 kernel: random: fast init done Sep 01 18:44:22 volumion2 kernel: random: crng init done Sep 01 18:44:22 volumion2 kernel: gpiomem-aml ff634000.gpiomem: Initialised: GPIO register area is 1 Sep 01 18:44:22 volumion2 kernel: gpiomem-aml ff634000.gpiomem: Initialised: Registers at start:0xff634000 end:0xff634fff size:0x00000fff Sep 01 18:44:22 volumion2 kernel: [drm] Initialized Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: Continuing without Mali regulator control Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: max pp is 2 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: set min pp to default 1 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: min pp is 1 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: min clk is 4 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: hiu io source 0xffffff80083bd000 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: hiu io source 0xffffff80083bf000 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: num of pp used most of time 1 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: clock dvfs cfg table size is 6 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: max clk set 4 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: max clk is 4 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: turbo clk set to 5 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: turbo clk is 5 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: default clk set to 4 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: default clk is 4 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: ====================0==================== clk_freq= 285714285, clk_parent=fclk_div7, voltage=1150, keep_count=5, threshod=<100 190>, clk_sample=285 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: ====================1==================== clk_freq= 400000000, clk_parent=fclk_div5, voltage=1150, keep_count=5, threshod=<152 207>, clk_sample=400 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: ====================2==================== clk_freq= 500000000, clk_parent=fclk_div4, voltage=1150, keep_count=5, threshod=<180 220>, clk_sample=500 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: ====================3==================== clk_freq= 666666666, clk_parent=fclk_div3, voltage=1150, keep_count=5, threshod=<210 236>, clk_sample=666 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: ====================4==================== clk_freq= 800000000, clk_parent=fclk_div2p5, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=800 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: ====================5==================== clk_freq= 800000000, clk_parent=fclk_div2p5, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=800 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: clock dvfs table size is 6 Sep 01 18:44:22 volumion2 kernel: mali_plat=ffffff800a6b8250 Sep 01 18:44:22 volumion2 kernel: meson_gcooldev is null, no set min status Sep 01 18:44:22 volumion2 kernel: gpu cooling register okay with err=0 Sep 01 18:44:22 volumion2 kernel: meson_gcooldev is null, no set min status Sep 01 18:44:22 volumion2 kernel: gpu core cooling register okay with err=0 Sep 01 18:44:22 volumion2 kernel: shader_present=3, tiler_present=1, l2_present=1 Sep 01 18:44:22 volumion2 kernel: Mali_pwr_on:gpu_irq : 200 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: GPU identified as 0x2 arch 7.2.1 r0p0 status 0 Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: Using coherency mode 0 set from dtb Sep 01 18:44:22 volumion2 kernel: mali ffe40000.bifrost: Probed as mali0 Sep 01 18:44:22 volumion2 kernel: Unable to detect cache hierarchy for CPU 0 Sep 01 18:44:22 volumion2 kernel: loop: module loaded Sep 01 18:44:22 volumion2 kernel: mtdoops: mtd device (mtddev=name/number) must be supplied Sep 01 18:44:22 volumion2 kernel: libphy: Fixed MDIO Bus: probed Sep 01 18:44:22 volumion2 kernel: REG0:Addr = ffffff80083c3540 Sep 01 18:44:22 volumion2 kernel: ee eth reset:Addr = ffffff80083c5008 Sep 01 18:44:22 volumion2 kernel: read auto_cali_idx fail Sep 01 18:44:22 volumion2 kernel: Not set cali_val for REG1 Sep 01 18:44:22 volumion2 kernel: meson6-dwmac ff3f0000.ethernet: no reset control found Sep 01 18:44:22 volumion2 kernel: stmmac - user ID: 0x11, Synopsys ID: 0x37 Sep 01 18:44:22 volumion2 kernel: Ring mode enabled Sep 01 18:44:22 volumion2 kernel: DMA HW capability register supported Sep 01 18:44:22 volumion2 kernel: Normal descriptors Sep 01 18:44:22 volumion2 kernel: RX Checksum Offload Engine supported Sep 01 18:44:22 volumion2 kernel: COE Type 2 Sep 01 18:44:22 volumion2 kernel: TX Checksum insertion supported Sep 01 18:44:22 volumion2 kernel: Wake-Up On Lan supported Sep 01 18:44:22 volumion2 kernel: eth%d: device MAC address 00:1e:06:43:c3:d7 Sep 01 18:44:22 volumion2 kernel: Enable RX Mitigation via HW Watchdog Timer Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: EDID Parser: Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 01 18:44:22 volumion2 kernel: [RX]-up_phy_addr = 2 Sep 01 18:44:22 volumion2 kernel: hdmitx: v2 VSVDB: len=11, sup_2160p60hz=1, Interface=2 Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: get dtd0 vic: 116 Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx: get PMT vic: 97 Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: find IEEEOUT Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: check sum valid Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: check sum valid Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: update rx hdr info d at edid parsing Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: check sum valid Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: check sum valid Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: blk0 raw data Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: 00ffffffffffff001e6dcd82010101010122010380a05a780aee91a3544c9926 0f5054a1080031404540614071408180d1c00101010108e80030f2705a80b058 8a0040846300001e6fc200a0a0a055503020350040846300001e000000fd0018 781eff77000a202020202020000000fc004c472054562053534352320a20019b Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: blk1 raw data Sep 01 18:44:22 volumion2 kernel: hdmitx: edid: 02036cf15d616076756665dbda101f04130514030212202122155d5e5f626364 3f40320957071507505707013d1ec05f7e076704076e030c002000b8442c0080 010203046dd85dc40178806b432878d33305e200cfe305c000e3060d01e20fff eb0146d000480276885a7595000000000000000000000000000000000000009b Sep 01 18:44:22 volumion2 kernel: hdmitx: system: update physcial size: 1600 900 Sep 01 18:44:22 volumion2 kernel: hdmitx: video: Sink is HDMI device Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: set audio Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 01 18:44:22 volumion2 kernel: hdmitx: fs = 0, cd = 4, tmds_clk = 593407 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: aud_n_para = 5824 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: set channel status Sep 01 18:44:22 volumion2 kernel: hdmitx: system: get current mode: 2160p60hz Sep 01 18:44:22 volumion2 kernel: hdmitx: system: update rx hdr info d Sep 01 18:44:22 volumion2 kernel: hdmitx: system: update physcial size: 1600 900 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: [set_disp_mode_auto] ALREADY init VIC = 97 Sep 01 18:44:22 volumion2 kernel: hdmitx: system: change to HDMI mode Sep 01 18:44:22 volumion2 kernel: libphy: stmmac: probed Sep 01 18:44:22 volumion2 kernel: eth%d: PHY ID 001cc916 at 0 IRQ POLL (stmmac-0:00) active Sep 01 18:44:22 volumion2 kernel: eth%d: PHY ID 001cc916 at 7 IRQ POLL (stmmac-0:07) Sep 01 18:44:22 volumion2 kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Sep 01 18:44:22 volumion2 kernel: ehci-pci: EHCI PCI platform driver Sep 01 18:44:22 volumion2 kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver cdc_acm Sep 01 18:44:22 volumion2 kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver usb-storage Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver usbserial Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver usbserial_generic Sep 01 18:44:22 volumion2 kernel: usbserial: USB Serial support registered for generic Sep 01 18:44:22 volumion2 kernel: mousedev: PS/2 mouse device common for all mice Sep 01 18:44:22 volumion2 kernel: i2c /dev entries driver Sep 01 18:44:22 volumion2 kernel: lirc_helper: wakeupkey 0xffffffff, protocol 0x1 Sep 01 18:44:22 volumion2 kernel: ledtrig-cpu: registered to indicate activity on CPUs Sep 01 18:44:22 volumion2 kernel: hidraw: raw HID events driver (C) Jiri Kosina Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver usbhid Sep 01 18:44:22 volumion2 kernel: usbhid: USB HID core driver Sep 01 18:44:22 volumion2 kernel: meson_cpufreq_init: ignor dsu clk! Sep 01 18:44:22 volumion2 kernel: meson_cpufreq_init: ignor dsu pre parent clk! Sep 01 18:44:22 volumion2 kernel: value of voltage_tolerance 0 Sep 01 18:44:22 volumion2 kernel: meson_cpufreq_init:don't find the node Sep 01 18:44:22 volumion2 kernel: value of gp1_clk_target 0 Sep 01 18:44:22 volumion2 kernel: dvfs [meson_cpufreq_init] - cluster 0 freq 2016000 Sep 01 18:44:22 volumion2 kernel: cpu cpu0: meson_cpufreq_init: CPU 0 initialized Sep 01 18:44:22 volumion2 kernel: meson_cpufreq_init: ignor dsu clk! Sep 01 18:44:22 volumion2 kernel: meson_cpufreq_init: ignor dsu pre parent clk! Sep 01 18:44:22 volumion2 kernel: value of voltage_tolerance 0 Sep 01 18:44:22 volumion2 kernel: meson_cpufreq_init:don't find the node Sep 01 18:44:22 volumion2 kernel: value of gp1_clk_target 0 Sep 01 18:44:22 volumion2 kernel: dvfs [meson_cpufreq_init] - cluster 1 freq 2304000 Sep 01 18:44:22 volumion2 kernel: dvfs [meson_cpufreq_init] - cluster 1 freq 2400000 Sep 01 18:44:22 volumion2 kernel: cpu cpu2: meson_cpufreq_init: CPU 2 initialized Sep 01 18:44:22 volumion2 kernel: ff803000.serial: clock gate not found Sep 01 18:44:22 volumion2 kernel: meson_uart ff803000.serial: ==uart0 reg addr = ffffff80083cb000 Sep 01 18:44:22 volumion2 kernel: ff803000.serial: ttyS0 at MMIO 0xff803000 (irq = 31, base_baud = 1500000) is a meson_uart Sep 01 18:44:22 volumion2 kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 0 to 115200 Sep 01 18:44:22 volumion2 kernel: console [ttyS0] enabled Sep 01 18:44:22 volumion2 kernel: amlogic-new-usb2-v2 ffe09000.usb2phy: USB2 phy probe:phy_mem:0xffe09000, iomap phy_base:0xffffff80083cd000 Sep 01 18:44:22 volumion2 kernel: amlogic-new-usb3-v2 ffe09080.usb3phy: USB3 phy probe:phy_mem:0xffe09080, iomap phy_base:0xffffff80083eb080 Sep 01 18:44:22 volumion2 kernel: aml_dma ff63e000.aml_dma: Aml dma Sep 01 18:44:22 volumion2 kernel: aml_aes_dma ff63e000.aml_dma:aml_aes: Aml AES_dma Sep 01 18:44:22 volumion2 kernel: aml_tdes_dma ff63e000.aml_dma:aml_tdes: Aml TDES_dma Sep 01 18:44:22 volumion2 kernel: aml_sha_dma ff63e000.aml_dma:aml_sha: Aml SHA1/SHA224/SHA256 dma Sep 01 18:44:22 volumion2 kernel: gpio-keypad ff800000.gpio_keypad: power key(116) registed. Sep 01 18:44:22 volumion2 kernel: input: gpio_keypad as /devices/platform/ff800000.gpio_keypad/input/input0 Sep 01 18:44:22 volumion2 kernel: efusekeynum: 1 Sep 01 18:44:22 volumion2 kernel: efusekeyname: uuid offset: 0 size: 32 Sep 01 18:44:22 volumion2 kernel: efuse efuse: probe OK! Sep 01 18:44:22 volumion2 kernel: ion_dev soc:ion_dev: assigned reserved memory node linux,ion-dev Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d_init_module Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d_dev major:236 Sep 01 18:44:22 volumion2 kernel: ge2d: clock source clk_ge2d_gate ffffffc029466280 Sep 01 18:44:22 volumion2 kernel: ge2d: clock clk_ge2d source ffffffc029466300 Sep 01 18:44:22 volumion2 kernel: ge2d: clock source clk_vapb_0 ffffffc029466380 Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d init clock is 500000000 HZ, VPU clock is 666666656 HZ Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d clock is 499 MHZ Sep 01 18:44:22 volumion2 kernel: ge2d: find address resource Sep 01 18:44:22 volumion2 kernel: ge2d: map io source 0x00000000ff940000,size=65536 to 0xffffff8008590000 Sep 01 18:44:22 volumion2 kernel: ge2d: reserved mem init failed Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d: pdev=ffffffc0ca272000, irq=44, clk=ffffffc029466280 Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d start monitor Sep 01 18:44:22 volumion2 kernel: ge2d: ge2d workqueue monitor start Sep 01 18:44:22 volumion2 kernel: [tsync_pcr_init]init success. Sep 01 18:44:22 volumion2 kernel: amvideom vsync irq: 45 Sep 01 18:44:22 volumion2 kernel: create_ge2d_work_queue video task ok Sep 01 18:44:22 volumion2 kernel: fb: osd_init_module Sep 01 18:44:22 volumion2 kernel: fb: viu vsync irq: 45 Sep 01 18:44:22 volumion2 kernel: fb: viu2 vsync irq: 54 Sep 01 18:44:22 volumion2 kernel: 0x000000db:Y=db,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000dc:Y=dc,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000dd:Y=dd,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000de:Y=de,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000df:Y=df,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000e0:Y=e0,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: fb: osd_rdma_init: rdma_table p=0xcf608000,op=0xcf608000 , v=0xffffff80083f7000 Sep 01 18:44:22 volumion2 kernel: rdma_register, rdma_table_addr ffffff80083f9000 rdma_table_addr_phy cf609000 reg_buf ffffffc0c9dd4000 Sep 01 18:44:22 volumion2 kernel: rdma_register success, handle 2 table_size 4096 Sep 01 18:44:22 volumion2 kernel: fb: osd_rdma_init:osd rdma handle = 2. Sep 01 18:44:22 volumion2 kernel: fb: mem_size: 0x800000 Sep 01 18:44:22 volumion2 kernel: fb: mem_size: 0x4b80000 Sep 01 18:44:22 volumion2 kernel: fb: mem_size: 0x100000 Sep 01 18:44:22 volumion2 kernel: fb: mem_size: 0x100000 Sep 01 18:44:22 volumion2 kernel: fb: mem_size: 0x800000 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: assigned reserved memory node linux,meson-fb Sep 01 18:44:22 volumion2 kernel: fb: reserved memory base:0x000000007f800000, size:800000 Sep 01 18:44:22 volumion2 kernel: fb: fb def : 3840 2160 3840 4320 32 Sep 01 18:44:22 volumion2 kernel: fb: init fbdev bpp is:32 Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 01 18:44:22 volumion2 kernel: fb: use ion buffer for fb memory, fb_index=0 Sep 01 18:44:22 volumion2 kernel: fb: OSD0 as afbcd mode,afbc_type=2 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: create ion_client ffffffc02944d300, handle=ffffffc0b9dcbe40 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: ion memory(0): created fb at 0x00000000e5800000, size 75 MiB Sep 01 18:44:22 volumion2 kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Sep 01 18:44:22 volumion2 kernel: fb: Frame buffer memory assigned at Sep 01 18:44:22 volumion2 kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Sep 01 18:44:22 volumion2 kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Sep 01 18:44:22 volumion2 kernel: fb: logo_index=0,fb_index=0 Sep 01 18:44:22 volumion2 kernel: fb: ---------------clear fb0 memory ffffff800a94f000 Sep 01 18:44:22 volumion2 kernel: clear:c4c00000, free:c4c00000, tick:635123 us Sep 01 18:44:22 volumion2 kernel: fb: osd[0] canvas.idx =0x40 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] canvas.addr=0xe5800000 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] canvas.width=15360 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] canvas.height=4320 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] frame.width=3840 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] frame.height=2160 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] out_addr_id =0x1 Sep 01 18:44:22 volumion2 kernel: Console: switching to colour frame buffer device 480x135 Sep 01 18:44:22 volumion2 kernel: fb: osd[0] enable: 1 (swapper/0) Sep 01 18:44:22 volumion2 kernel: fb: set osd0 reverse as NONE Sep 01 18:44:22 volumion2 kernel: fb: osd probe OK Sep 01 18:44:22 volumion2 kernel: hdmitx: hdcp: hdmitx_hdcp_init Sep 01 18:44:22 volumion2 kernel: vout: vout2: create vout2 attribute OK Sep 01 18:44:22 volumion2 kernel: vout: vout2: vout2_fops_create OK Sep 01 18:44:22 volumion2 kernel: vout: vout2: clktree_init Sep 01 18:44:22 volumion2 kernel: vout: vout2: register server: nulldisp_vout2_server Sep 01 18:44:22 volumion2 kernel: vout: vout2: init mode null set ok Sep 01 18:44:22 volumion2 kernel: vout: vout2: aml_vout2_probe OK Sep 01 18:44:22 volumion2 kernel: DI: di_module_init ok. Sep 01 18:44:22 volumion2 kernel: DI: di_probe: Sep 01 18:44:22 volumion2 kernel: DI: di_probe: major 510 Sep 01 18:44:22 volumion2 kernel: deinterlace deinterlace: assigned reserved memory node linux,di_cma Sep 01 18:44:22 volumion2 kernel: di:flag_cma=1 Sep 01 18:44:22 volumion2 kernel: DI: CMA size 0x2800000. Sep 01 18:44:22 volumion2 kernel: pre_irq:68 Sep 01 18:44:22 volumion2 kernel: post_irq:69 Sep 01 18:44:22 volumion2 kernel: DI: di_probe allocate rdma channel 0. Sep 01 18:44:22 volumion2 kernel: di_get_vpu_clkb: get clk vpu error. Sep 01 18:44:22 volumion2 kernel: DI: vpu clkb <334000000, 667000000> Sep 01 18:44:22 volumion2 kernel: get clkb rate:333333328 Sep 01 18:44:22 volumion2 kernel: DI:enable vpu clkb. Sep 01 18:44:22 volumion2 kernel: 0x000000e1:Y=e1,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000e2:Y=e2,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000e3:Y=e3,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f0:Y=f0,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f1:Y=f1,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f2:Y=f2,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f3:Y=f3,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f4:Y=f4,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f5:Y=f5,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f6:Y=f6,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f7:Y=f7,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f8:Y=f8,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000f9:Y=f9,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000fa:Y=fa,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000fb:Y=fb,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000fc:Y=fc,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000fd:Y=fd,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000fe:Y=fe,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x000000ff:Y=ff,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x0000003a:Y=3a,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x0000003b:Y=3b,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x0000003c:Y=3c,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x0000003d:Y=3d,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x0000003e:Y=3e,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: 0x0000003f:Y=3f,U=0,V=0 Sep 01 18:44:22 volumion2 kernel: DI: support multi decoding 61~62~63. Sep 01 18:44:22 volumion2 kernel: DI: di_probe:Di use HRTIMER Sep 01 18:44:22 volumion2 kernel: DI: di_probe:ok Sep 01 18:44:22 volumion2 kernel: dim:dim_module_init Sep 01 18:44:22 volumion2 kernel: dim:dim_module_init finish Sep 01 18:44:22 volumion2 kernel: dil:dil_init. Sep 01 18:44:22 volumion2 kernel: dil:dil_init ok. Sep 01 18:44:22 volumion2 kernel: vdin_drv_init: major 509 Sep 01 18:44:22 volumion2 kernel: vdin_drv_init: vdin driver init done Sep 01 18:44:22 volumion2 kernel: [viuin..]viuin_init_module viuin module init Sep 01 18:44:22 volumion2 kernel: [viuin..]viuin_probe probe ok. Sep 01 18:44:22 volumion2 kernel: [RX]-hdmirx: hdmirx_init. Sep 01 18:44:22 volumion2 kernel: ESM HLD: Initializing... Sep 01 18:44:22 volumion2 kernel: amlvid:info: amlvideo_init called Sep 01 18:44:22 volumion2 kernel: amlvid:info: amlvideo_create_instance called Sep 01 18:44:22 volumion2 kernel: amlvid:info: v4l2_dev.name=:amlvideo-000 Sep 01 18:44:22 volumion2 kernel: amlvideo-000: V4L2 device registered as video10 Sep 01 18:44:22 volumion2 kernel: amlvid:info: amlvideo_create_instance called Sep 01 18:44:22 volumion2 kernel: amlvid:info: v4l2_dev.name=:amlvideo-001 Sep 01 18:44:22 volumion2 kernel: amlvideo-001: V4L2 device registered as video23 Sep 01 18:44:22 volumion2 kernel: PPMGRDRV: warn: ppmgr module init func called Sep 01 18:44:22 volumion2 kernel: PPMGRDRV: info: ppmgr_driver_probe called Sep 01 18:44:22 volumion2 kernel: Reserved memory: failed to init DMA memory pool at 0x00000000e3000000, size 0 MiB Sep 01 18:44:22 volumion2 kernel: PPMGRDRV: info: ppmgr_dev major:507 Sep 01 18:44:22 volumion2 kernel: ionvideo-000: V4L2 device registered as video13 Sep 01 18:44:22 volumion2 kernel: ionvideo-001: V4L2 device registered as video14 Sep 01 18:44:22 volumion2 kernel: ionvideo-002: V4L2 device registered as video15 Sep 01 18:44:22 volumion2 kernel: ionvideo-003: V4L2 device registered as video16 Sep 01 18:44:22 volumion2 kernel: ionvideo-004: V4L2 device registered as video17 Sep 01 18:44:22 volumion2 kernel: ionvideo-005: V4L2 device registered as video18 Sep 01 18:44:22 volumion2 kernel: ionvideo-006: V4L2 device registered as video19 Sep 01 18:44:22 volumion2 kernel: ionvideo-007: V4L2 device registered as video20 Sep 01 18:44:22 volumion2 kernel: ionvideo-008: V4L2 device registered as video21 Sep 01 18:44:22 volumion2 kernel: ionvid: info: Video Technology Magazine Ion Video Sep 01 18:44:22 volumion2 kernel: ionvid: info: Capture Board ver 1.0 successfully loaded Sep 01 18:44:22 volumion2 kernel: videosync_create_instance dev_s ffffffc0c9e02c00,dev_s->dev ffffffc029511600 Sep 01 18:44:22 volumion2 kernel: videosync_create_instance reg videosync.0 Sep 01 18:44:22 volumion2 kernel: aml_vecm_init:module init Sep 01 18:44:22 volumion2 kernel: videosync_thread started Sep 01 18:44:22 volumion2 kernel: VECM probe start Sep 01 18:44:22 volumion2 kernel: Can't find detect_colorbar. Sep 01 18:44:22 volumion2 kernel: Can't find detect_face. Sep 01 18:44:22 volumion2 kernel: Can't find detect_corn. Sep 01 18:44:22 volumion2 kernel: Can't find wb_sel. Sep 01 18:44:22 volumion2 kernel: hdr:Can't find cfg_en_osd_100. Sep 01 18:44:22 volumion2 kernel: Can't find tx_op_color_primary. Sep 01 18:44:22 volumion2 kernel: amlogic, vecm Sep 01 18:44:22 volumion2 kernel: vlock dt support: 1 Sep 01 18:44:22 volumion2 kernel: vlock dt new_fsm: 0 Sep 01 18:44:22 volumion2 kernel: vlock dt hwver: 0 Sep 01 18:44:22 volumion2 kernel: vlock dt phlock_en: 0 Sep 01 18:44:22 volumion2 kernel: Can't find vlock_en. Sep 01 18:44:22 volumion2 kernel: Can't find vlock_mode. Sep 01 18:44:22 volumion2 kernel: Can't find vlock_pll_m_limit. Sep 01 18:44:22 volumion2 kernel: Can't find vlock_line_limit. Sep 01 18:44:22 volumion2 kernel: param_config vlock_en:1 md=0x4 Sep 01 18:44:22 volumion2 kernel: vlock: maxLine 524,maxPixel 1715 Sep 01 18:44:22 volumion2 kernel: vlock_status_init vlock_en:1 Sep 01 18:44:22 volumion2 kernel: aml_vecm_probe: ok Sep 01 18:44:22 volumion2 kernel: amdolby_vision_init:module init Sep 01 18:44:22 volumion2 kernel: amdolby_vision probe start & ver: 20181220 Sep 01 18:44:22 volumion2 kernel: cpu_id=2 tvmode=0 Sep 01 18:44:22 volumion2 kernel: dolby_vision_init_receiver(dvel) Sep 01 18:44:22 volumion2 kernel: dolby_vision_init_receiver: dvel Sep 01 18:44:22 volumion2 kernel: amdolby_vision_probe: ok Sep 01 18:44:22 volumion2 kernel: dovi disable in uboot Sep 01 18:44:22 volumion2 kernel: reg base = ffffff8008587000 Sep 01 18:44:22 volumion2 kernel: meson-mmc: mmc driver version: 3.02, 2017-05-15: New Emmc Host Controller Sep 01 18:44:22 volumion2 kernel: meson-mmc: >>>>>>>>hostbase ffffff80085a1000, dmode Sep 01 18:44:22 volumion2 kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Sep 01 18:44:22 volumion2 kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Sep 01 18:44:22 volumion2 kernel: hdmitx: hdmitx_set_drm_pkt: tf=1, cf=1, colormetry=0 Sep 01 18:44:22 volumion2 kernel: meson-mmc: meson_mmc_probe() : success! Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: resp_timeout,vstat:0xa1ff2800,virqc:3fff Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: err: wait for irq service, bus_fsm:0x8 Sep 01 18:44:22 volumion2 kernel: meson-mmc: meson_mmc_irq_thread_v3() 653: set 1st retry! Sep 01 18:44:22 volumion2 kernel: meson-mmc: retry cmd 1 the 3-th time(s) Sep 01 18:44:22 volumion2 kernel: meson-mmc: >>>>>>>>hostbase ffffff80085aa000, dmode Sep 01 18:44:22 volumion2 kernel: meson-mmc: gpio_cd = 1ca Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: resp_timeout,vstat:0xa1ff2800,virqc:3fff Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: err: wait for irq service, bus_fsm:0x8 Sep 01 18:44:22 volumion2 kernel: meson-mmc: retry cmd 1 the 2-th time(s) Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Sep 01 18:44:22 volumion2 kernel: meson-mmc: retry cmd 1 the 1-th time(s) Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Sep 01 18:44:22 volumion2 kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Sep 01 18:44:22 volumion2 kernel: meson-mmc: Command retried failed line:675, cmd:1 Sep 01 18:44:22 volumion2 kernel: meson-mmc: meson_mmc_probe() : success! Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: cec driver date:2019/10/22: finetune ARB rising time Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: compatible:amlogic, aocec-g12a Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: cecb_ver:0x1 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: line_reg:0x1 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: line_bit:0x3 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: ee_to_ao:0x1 Sep 01 18:44:22 volumion2 kernel: input: cec_input as /devices/virtual/input/input1 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: not find 'port_num' Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: using cec:1 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: no hdmirx regs Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: no hhi regs Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: not find 'output' Sep 01 18:44:22 volumion2 kernel: irq cnt:2, a:43, b42 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: wakeup_reason:0x2 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: cev val1: 0x0;val2: 0x0 Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: aml_cec_probe success end Sep 01 18:44:22 volumion2 kernel: unifykey: storage in base: 0xffffffc005000000 Sep 01 18:44:22 volumion2 kernel: unifykey: storage out base: 0xffffffc005040000 Sep 01 18:44:22 volumion2 kernel: unifykey: storage block base: 0xffffffc005080000 Sep 01 18:44:22 volumion2 kernel: unifykey: probe done! Sep 01 18:44:22 volumion2 kernel: unifykey: aml_unifykeys_init done! Sep 01 18:44:22 volumion2 kernel: meson ts init Sep 01 18:44:22 volumion2 kernel: tsensor id: 0 Sep 01 18:44:22 volumion2 kernel: r1p1_tsensor_read valid cnt is 0 Sep 01 18:44:22 volumion2 kernel: tsensor trim info: 0xfa008154! Sep 01 18:44:22 volumion2 kernel: tsensor hireboot: 0xc0ff2b50 Sep 01 18:44:22 volumion2 kernel: meson ts init Sep 01 18:44:22 volumion2 kernel: tsensor id: 1 Sep 01 18:44:22 volumion2 kernel: r1p1_tsensor_read valid cnt is 0 Sep 01 18:44:22 volumion2 kernel: tsensor trim info: 0xfa0080c9! Sep 01 18:44:22 volumion2 kernel: tsensor hireboot: 0xc0ff2af0 Sep 01 18:44:22 volumion2 kernel: audio_dsp: [dsp]register dsp to char divece(257) Sep 01 18:44:22 volumion2 kernel: amaudio: amaudio: driver amaudio init! Sep 01 18:44:22 volumion2 kernel: amaudio: amaudio_init - amaudio: driver amaudio succuess! Sep 01 18:44:22 volumion2 kernel: amlkaraoke init success! Sep 01 18:44:22 volumion2 kernel: sysled: module init Sep 01 18:44:22 volumion2 kernel: meson_wdt ffd0f0d0.watchdog: start watchdog Sep 01 18:44:22 volumion2 kernel: meson_wdt ffd0f0d0.watchdog: creat work queue for watch dog Sep 01 18:44:22 volumion2 kernel: meson_wdt ffd0f0d0.watchdog: AML Watchdog Timer probed done Sep 01 18:44:22 volumion2 kernel: meson-saradc ff809000.saradc: set delay per tick to <1ms> by default. Sep 01 18:44:22 volumion2 kernel: meson-saradc ff809000.saradc: set ticks per period to <1> by default. Sep 01 18:44:22 volumion2 kernel: dmc_monitor_probe Sep 01 18:44:22 volumion2 kernel: atv_demod: aml_atvdemod_init: OK, atv demod version: V2.15. Sep 01 18:44:22 volumion2 kernel: defendkey ff630218.defendkey: Reserved memory is not enough! Sep 01 18:44:22 volumion2 kernel: defendkey: probe of ff630218.defendkey failed with error -22 Sep 01 18:44:22 volumion2 kernel: usbcore: registered new interface driver snd-usb-audio Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: aml_T9015_audio_codec_probe Sep 01 18:44:22 volumion2 kernel: T9015 acodec used by auge, tdmout:2 Sep 01 18:44:22 volumion2 kernel: Error: Driver 'spdif-dit' is already registered, aborting... Sep 01 18:44:22 volumion2 kernel: asoc debug: aml_audio_controller_probe-130 Sep 01 18:44:22 volumion2 kernel: aml_tdm_platform_probe, tdm ID = 2, lane_cnt = 4 Sep 01 18:44:22 volumion2 kernel: TDM id 2 i2s2hdmi:0 Sep 01 18:44:22 volumion2 kernel: snd_tdm ff642000.audiobus:tdmc: lane_mask_out = 1, lane_oe_mask_out = 0 Sep 01 18:44:22 volumion2 kernel: snd_tdm ff642000.audiobus:tdmc: neither mclk_pad nor mclk2pad set Sep 01 18:44:22 volumion2 kernel: snd_tdm ff642000.audiobus:tdmc: aml_tdm_get_pins error! Sep 01 18:44:22 volumion2 kernel: aml_tdm_platform_probe(), share en = 1 Sep 01 18:44:22 volumion2 kernel: No channel mask node Channel_Mask Sep 01 18:44:22 volumion2 kernel: aml_spdif_platform_probe, spdif ID = 0 Sep 01 18:44:22 volumion2 kernel: SPDIF id 0 asrc_id:0 auto_asrc:0 Sep 01 18:44:22 volumion2 kernel: spdifout_play_with_zerodata, spdif id:0 enable:0 Sep 01 18:44:22 volumion2 kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Sep 01 18:44:22 volumion2 kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Sep 01 18:44:22 volumion2 kernel: hdmitx: audio: aout notify format CT_PCM Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: set audio Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 01 18:44:22 volumion2 kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 593407 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: aud_n_para = 5824 Sep 01 18:44:22 volumion2 kernel: hdmitx: hw: set channel status Sep 01 18:44:22 volumion2 kernel: hdmitx: audio: Audio Type: PCM Sep 01 18:44:22 volumion2 kernel: hdmitx: audio: set audio param Sep 01 18:44:22 volumion2 kernel: spdif_a is set to enable Sep 01 18:44:22 volumion2 kernel: aml_spdif_platform_probe, register soc platform Sep 01 18:44:22 volumion2 kernel: aml_spdif_platform_probe, spdif ID = 1 Sep 01 18:44:22 volumion2 kernel: spdifout_play_with_zerodata, spdif id:1 enable:0 Sep 01 18:44:22 volumion2 kernel: spdif_b fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Sep 01 18:44:22 volumion2 kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Sep 01 18:44:22 volumion2 kernel: spdif_b is set to enable Sep 01 18:44:22 volumion2 kernel: aml_spdif_platform_probe, register soc platform Sep 01 18:44:22 volumion2 kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 0, irqs toddr 32, frddr 35 Sep 01 18:44:22 volumion2 kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 1, irqs toddr 33, frddr 36 Sep 01 18:44:22 volumion2 kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 2, irqs toddr 34, frddr 37 Sep 01 18:44:22 volumion2 kernel: audiolocker_platform_probe Sep 01 18:44:22 volumion2 kernel: Register vad Sep 01 18:44:22 volumion2 kernel: meson-mmc: card IN Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLP_SEL_DACL -> Lineout left P switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLP_SEL_DACL_INV -> Lineout left P switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLN_SEL_DACL_INV -> Lineout left N switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLN_SEL_DACL -> Lineout left N switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORP_SEL_DACR -> Lineout right P switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORP_SEL_DACR_INV -> Lineout right P switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORN_SEL_DACR_INV -> Lineout right N switch Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORN_SEL_DACR -> Lineout right N switch Sep 01 18:44:22 volumion2 kernel: aml_dai_spdif_probe Sep 01 18:44:22 volumion2 kernel: aml_dai_spdif_probe Sep 01 18:44:22 volumion2 kernel: set normal 512 fs /4 fs Sep 01 18:44:22 volumion2 kernel: set spdifout clk:6144000, mpll:24576000 Sep 01 18:44:22 volumion2 kernel: get spdifout clk:6143997, mpll:24575987 Sep 01 18:44:22 volumion2 kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Sep 01 18:44:22 volumion2 kernel: aml_spdif_new spdif_b, clk continuous:1 Sep 01 18:44:22 volumion2 kernel: spdifout_play_with_zerodata_free, spdif id:1 Sep 01 18:44:22 volumion2 kernel: asoc-aml-card auge_sound: dit-hifi <-> SPDIF-B mapping ok Sep 01 18:44:22 volumion2 kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Sep 01 18:44:22 volumion2 kernel: aml_dai_set_tdm_slot(), txmask(0x3), rxmask(0x3) Sep 01 18:44:22 volumion2 kernel: lanes_out_cnt(1), lanes_in_cnt(1) Sep 01 18:44:22 volumion2 kernel: lanes_lb_cnt(0) Sep 01 18:44:22 volumion2 kernel: slots(2), slot_width(32) Sep 01 18:44:22 volumion2 kernel: lanes_oe_out_cnt(0), lanes_oe_in_cnt(0) Sep 01 18:44:22 volumion2 kernel: asoc aml_dai_set_tdm_fmt, 0x4011, ffffffc0ca16ba18, id(2), clksel(2) Sep 01 18:44:22 volumion2 kernel: master_mode(1), binv(1), finv(1) out_skew(2), in_skew(3) Sep 01 18:44:22 volumion2 kernel: asoc-aml-card auge_sound: T9015-audio-hifi <-> TDM-C mapping ok Sep 01 18:44:22 volumion2 kernel: set normal 512 fs /4 fs Sep 01 18:44:22 volumion2 kernel: set spdifout clk:6144000, mpll:24576000 Sep 01 18:44:22 volumion2 kernel: get spdifout clk:6143997, mpll:24575987 Sep 01 18:44:22 volumion2 kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Sep 01 18:44:22 volumion2 kernel: aml_spdif_new spdif_a, clk continuous:1 Sep 01 18:44:22 volumion2 kernel: spdifout_play_with_zerodata_free, spdif id:0 Sep 01 18:44:22 volumion2 kernel: asoc-aml-card auge_sound: dit-hifi <-> SPDIF mapping ok Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout left P switch has no paths Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout left N switch has no paths Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout right P switch has no paths Sep 01 18:44:22 volumion2 kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout right N switch has no paths Sep 01 18:44:22 volumion2 kernel: snd_card_add_kcontrols card:ffffffc029589018 Sep 01 18:44:22 volumion2 kernel: effect_v2 is not init Sep 01 18:44:22 volumion2 kernel: Not init vad Sep 01 18:44:22 volumion2 kernel: Failed to add VAD controls Sep 01 18:44:22 volumion2 kernel: eq/drc v1 function enable Sep 01 18:44:22 volumion2 kernel: Netfilter messages via NETLINK v0.30. Sep 01 18:44:22 volumion2 kernel: Initializing XFRM netlink socket Sep 01 18:44:22 volumion2 kernel: NET: Registered protocol family 17 Sep 01 18:44:22 volumion2 kernel: Key type dns_resolver registered Sep 01 18:44:22 volumion2 kernel: Registered swp emulation handler Sep 01 18:44:22 volumion2 kernel: Registered cp15_barrier emulation handler Sep 01 18:44:22 volumion2 kernel: Registered setend emulation handler Sep 01 18:44:22 volumion2 kernel: registered taskstats version 1 Sep 01 18:44:22 volumion2 kernel: dwc3 ff500000.dwc3: Configuration mismatch. dr_mode forced to host Sep 01 18:44:22 volumion2 kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Sep 01 18:44:22 volumion2 kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 Sep 01 18:44:22 volumion2 kernel: usb: xhci: determined sg_tablesize: 4294967295 Sep 01 18:44:22 volumion2 kernel: xhci-hcd xhci-hcd.0.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x20010010 Sep 01 18:44:22 volumion2 kernel: xhci-hcd xhci-hcd.0.auto: irq 23, io mem 0xff500000 Sep 01 18:44:22 volumion2 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Sep 01 18:44:22 volumion2 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 01 18:44:22 volumion2 kernel: usb usb1: Product: xHCI Host Controller Sep 01 18:44:22 volumion2 kernel: usb usb1: Manufacturer: Linux 4.9.241+ xhci-hcd Sep 01 18:44:22 volumion2 kernel: usb usb1: SerialNumber: xhci-hcd.0.auto Sep 01 18:44:22 volumion2 kernel: hub 1-0:1.0: USB hub found Sep 01 18:44:22 volumion2 kernel: hub 1-0:1.0: 2 ports detected Sep 01 18:44:22 volumion2 kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Sep 01 18:44:22 volumion2 kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 Sep 01 18:44:22 volumion2 kernel: usb: xhci: determined sg_tablesize: 4294967295 Sep 01 18:44:22 volumion2 kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Sep 01 18:44:22 volumion2 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 Sep 01 18:44:22 volumion2 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 01 18:44:22 volumion2 kernel: usb usb2: Product: xHCI Host Controller Sep 01 18:44:22 volumion2 kernel: usb usb2: Manufacturer: Linux 4.9.241+ xhci-hcd Sep 01 18:44:22 volumion2 kernel: usb usb2: SerialNumber: xhci-hcd.0.auto Sep 01 18:44:22 volumion2 kernel: hub 2-0:1.0: USB hub found Sep 01 18:44:22 volumion2 kernel: hub 2-0:1.0: 1 port detected Sep 01 18:44:22 volumion2 kernel: hctosys: unable to open rtc device (rtc0) Sep 01 18:44:22 volumion2 kernel: dwc_otg ff400000.dwc2_a: dwc_otg_driver_probe(ffffffc0ca1d5c00) Sep 01 18:44:22 volumion2 kernel: dwc_otg: usb0: type: 2 speed: 0, config: 0, dma: 0, id: 0, phy: ffe09000, ctrl: 0 Sep 01 18:44:22 volumion2 kernel: dwc_otg ff400000.dwc2_a: base=0xffffff8008c00000 Sep 01 18:44:22 volumion2 kernel: dwc_otg ff400000.dwc2_a: dwc_otg_device=0xffffffc0b9e4f000 Sep 01 18:44:22 volumion2 kernel: meson-mmc: normal card in Sep 01 18:44:22 volumion2 kernel: dwc_otg: Core Release: 3.30a Sep 01 18:44:22 volumion2 kernel: dwc_otg: Setting default values for core params Sep 01 18:44:22 volumion2 kernel: dwc_otg: curmode: 0, host_only: 0 Sep 01 18:44:22 volumion2 kernel: dwc_otg ff400000.dwc2_a: DMA config: BURST_DEFAULT Sep 01 18:44:22 volumion2 kernel: dwc_otg: Using Buffer DMA mode Sep 01 18:44:22 volumion2 kernel: dwc_otg: OTG VER PARAM: 1, OTG VER FLAG: 1 Sep 01 18:44:22 volumion2 kernel: dwc_otg: Working on port type = SLAVE Sep 01 18:44:22 volumion2 kernel: dwc_otg: Dedicated Tx FIFOs mode Sep 01 18:44:22 volumion2 kernel: meson_cdev probe Sep 01 18:44:22 volumion2 kernel: meson_cdev index: 0 Sep 01 18:44:22 volumion2 kernel: thermal thermal_zone0: binding zone soc_thermal with cdev thermal-cpufreq-0 failed:-22 Sep 01 18:44:22 volumion2 kernel: meson_cdev index: 1 Sep 01 18:44:22 volumion2 kernel: thermal: no cluster id, cpucore as one cooldev Sep 01 18:44:22 volumion2 kernel: meson_cdev index: 2 Sep 01 18:44:22 volumion2 kernel: cpucore_cooling_register, max_cpu_core_num:6 Sep 01 18:44:22 volumion2 kernel: cpucore_cooling_register, clutser[1] core num:4 Sep 01 18:44:22 volumion2 kernel: cpucore_cooling_register, clutser[0] core num:2 Sep 01 18:44:22 volumion2 kernel: meson_cdev index: 3 Sep 01 18:44:22 volumion2 kernel: meson_cdev index: 4 Sep 01 18:44:22 volumion2 kernel: find tzd id: 0 Sep 01 18:44:22 volumion2 kernel: find tzd id: 0 Sep 01 18:44:22 volumion2 kernel: find tzd id: 0 Sep 01 18:44:22 volumion2 kernel: meson_cdev probe done Sep 01 18:44:22 volumion2 kernel: gxbb_pm: enter meson_pm_probe! Sep 01 18:44:22 volumion2 kernel: no vddio3v3_en pin Sep 01 18:44:22 volumion2 kernel: pm-meson aml_pm: Can't get switch_clk81 Sep 01 18:44:22 volumion2 kernel: gxbb_pm: meson_pm_probe done Sep 01 18:44:22 volumion2 kernel: ALSA device list: Sep 01 18:44:22 volumion2 kernel: #0: AML-AUGESOUND Sep 01 18:44:22 volumion2 kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 115200 to 115200 Sep 01 18:44:22 volumion2 kernel: Freeing unused kernel memory: 4992K Sep 01 18:44:22 volumion2 kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Sep 01 18:44:22 volumion2 kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Sep 01 18:44:22 volumion2 kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Sep 01 18:44:22 volumion2 kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Sep 01 18:44:22 volumion2 kernel: meson-aml-mmc ffe05000.sd: divider requested rate 200000000 != actual rate 199999997: ret=0 Sep 01 18:44:22 volumion2 kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Sep 01 18:44:22 volumion2 kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Sep 01 18:44:22 volumion2 kernel: meson-mmc: Data 1 aligned delay is 0 Sep 01 18:44:22 volumion2 kernel: meson-mmc: sd: clk 199999997 tuning start Sep 01 18:44:22 volumion2 kernel: meson-mmc: sd: adj_win: < 0 2 3 4 > Sep 01 18:44:22 volumion2 kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Sep 01 18:44:22 volumion2 kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Sep 01 18:44:22 volumion2 kernel: meson-mmc: sd: adj_win: < 0 1 2 3 4 > Sep 01 18:44:22 volumion2 kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Sep 01 18:44:22 volumion2 kernel: meson-mmc: sd: adj_win: < 0 1 3 4 > Sep 01 18:44:22 volumion2 kernel: meson-mmc: left:3, right:0, mid:4, size:4 Sep 01 18:44:22 volumion2 kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Sep 01 18:44:22 volumion2 kernel: meson-mmc: sd: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x42000 Sep 01 18:44:22 volumion2 kernel: meson-mmc: delay1:0x0, delay2:0x0 Sep 01 18:44:22 volumion2 kernel: sd: new ultra high speed SDR104 SDXC card at address aaaa Sep 01 18:44:22 volumion2 kernel: sd: clock 199999997, 4-bit-bus-width Sep 01 18:44:22 volumion2 kernel: mmcblk1: sd:aaaa SC64G 59.5 GiB Sep 01 18:44:22 volumion2 kernel: mmcblk1: p1 p2 p3 Sep 01 18:44:22 volumion2 kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Sep 01 18:44:22 volumion2 kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 01 18:44:22 volumion2 kernel: usb 1-1: Product: USB2.1 Hub Sep 01 18:44:22 volumion2 kernel: usb 1-1: Manufacturer: GenesysLogic Sep 01 18:44:22 volumion2 kernel: hub 1-1:1.0: USB hub found Sep 01 18:44:22 volumion2 kernel: hub 1-1:1.0: 4 ports detected Sep 01 18:44:22 volumion2 kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd Sep 01 18:44:22 volumion2 kernel: usb 2-1: New USB device found, idVendor=05e3, idProduct=0620 Sep 01 18:44:22 volumion2 kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 01 18:44:22 volumion2 kernel: usb 2-1: Product: USB3.1 Hub Sep 01 18:44:22 volumion2 kernel: usb 2-1: Manufacturer: GenesysLogic Sep 01 18:44:22 volumion2 kernel: hub 2-1:1.0: USB hub found Sep 01 18:44:22 volumion2 kernel: hub 2-1:1.0: 4 ports detected Sep 01 18:44:22 volumion2 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 01 18:44:22 volumion2 kernel: usb 1-1.3: new full-speed USB device number 3 using xhci-hcd Sep 01 18:44:22 volumion2 kernel: usb 1-1.3: New USB device found, idVendor=046d, idProduct=c52b Sep 01 18:44:22 volumion2 kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 01 18:44:22 volumion2 kernel: usb 1-1.3: Product: USB Receiver Sep 01 18:44:22 volumion2 kernel: usb 1-1.3: Manufacturer: Logitech Sep 01 18:44:22 volumion2 kernel: logitech-djreceiver 0003:046D:C52B.0003: hiddev0,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0.auto-1.3/input2 Sep 01 18:44:22 volumion2 kernel: input: Logitech K400 Plus as /devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.3/1-1.3:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input2 Sep 01 18:44:22 volumion2 kernel: logitech-hidpp-device 0003:046D:404D.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0.auto-1.3:1 Sep 01 18:44:22 volumion2 kernel: EXT4-fs (mmcblk1p2): recovery complete Sep 01 18:44:22 volumion2 kernel: EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 01 18:44:22 volumion2 kernel: fb: use ion buffer for fb memory, fb_index=1 Sep 01 18:44:22 volumion2 kernel: fb: OSD1 as afbcd mode,afbc_type=2 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: create ion_client ffffffc02944d300, handle=ffffffc0297ff580 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: ion memory(1): created fb at 0x00000000ea400000, size 1 MiB Sep 01 18:44:22 volumion2 kernel: fb: 1, phy: 0x00000000ea400000, vir:0xffffff8008c5e000, size=1024K Sep 01 18:44:22 volumion2 kernel: fb: Frame buffer memory assigned at Sep 01 18:44:22 volumion2 kernel: fb: 1, phy: 0x00000000ea400000, vir:0xffffff8008c5e000, size=1024K Sep 01 18:44:22 volumion2 kernel: fb: 1, phy: 0x00000000ea400000, vir:0xffffff8008c5e000, size=1024K Sep 01 18:44:22 volumion2 kernel: fb: logo_index=0,fb_index=1 Sep 01 18:44:22 volumion2 kernel: fb: ---------------clear fb1 memory ffffff8008c5e000 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] canvas.idx =0x43 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] canvas.addr=0xea400000 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] canvas.width=128 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] canvas.height=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] frame.width=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] frame.height=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[1] out_addr_id =0x2 Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 01 18:44:22 volumion2 kernel: fb: use ion buffer for fb memory, fb_index=2 Sep 01 18:44:22 volumion2 kernel: fb: OSD2 as afbcd mode,afbc_type=2 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: create ion_client ffffffc02944d300, handle=ffffffc0297ffd80 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: ion memory(2): created fb at 0x00000000ea500000, size 1 MiB Sep 01 18:44:22 volumion2 kernel: fb: 2, phy: 0x00000000ea500000, vir:0xffffff8008d5f000, size=1024K Sep 01 18:44:22 volumion2 kernel: fb: Frame buffer memory assigned at Sep 01 18:44:22 volumion2 kernel: fb: 2, phy: 0x00000000ea500000, vir:0xffffff8008d5f000, size=1024K Sep 01 18:44:22 volumion2 kernel: fb: 2, phy: 0x00000000ea500000, vir:0xffffff8008d5f000, size=1024K Sep 01 18:44:22 volumion2 kernel: fb: logo_index=0,fb_index=2 Sep 01 18:44:22 volumion2 kernel: fb: ---------------clear fb2 memory ffffff8008d5f000 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] canvas.idx =0x41 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] canvas.addr=0xea500000 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] canvas.width=128 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] canvas.height=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] frame.width=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] frame.height=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[2] out_addr_id =0x3 Sep 01 18:44:22 volumion2 kernel: fb: vpu clkc clock is 199 MHZ Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 01 18:44:22 volumion2 kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 01 18:44:22 volumion2 kernel: fb: use ion buffer for fb memory, fb_index=3 Sep 01 18:44:22 volumion2 kernel: fb: OSD3 as afbcd mode,afbc_type=2 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: create ion_client ffffffc02944d300, handle=ffffffc0297ffe40 Sep 01 18:44:22 volumion2 kernel: meson-fb meson-fb: ion memory(3): created fb at 0x00000000ea600000, size 8 MiB Sep 01 18:44:22 volumion2 kernel: fb: 3, phy: 0x00000000ea600000, vir:0xffffff800f4d0000, size=8192K Sep 01 18:44:22 volumion2 kernel: fb: Frame buffer memory assigned at Sep 01 18:44:22 volumion2 kernel: fb: 3, phy: 0x00000000ea600000, vir:0xffffff800f4d0000, size=8192K Sep 01 18:44:22 volumion2 kernel: fb: 3, phy: 0x00000000ea600000, vir:0xffffff800f4d0000, size=8192K Sep 01 18:44:22 volumion2 kernel: fb: logo_index=0,fb_index=3 Sep 01 18:44:22 volumion2 kernel: fb: ---------------clear fb3 memory ffffff800f4d0000 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] canvas.idx =0x42 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] canvas.addr=0xea600000 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] canvas.width=128 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] canvas.height=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] frame.width=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] frame.height=32 Sep 01 18:44:22 volumion2 kernel: fb: osd[3] out_addr_id =0x4 Sep 01 18:44:22 volumion2 kernel: EXT4-fs (mmcblk1p3): recovery complete Sep 01 18:44:22 volumion2 kernel: EXT4-fs (mmcblk1p3): mounted filesystem with ordered data mode. Opts: (null) Sep 01 18:44:22 volumion2 kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 01 18:44:22 volumion2 systemd[1]: System time before build time, advancing clock. Sep 01 18:44:22 volumion2 kernel: NET: Registered protocol family 10 Sep 01 18:44:22 volumion2 kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Sep 01 18:44:22 volumion2 kernel: cgroup: cgroup2: unknown option "nsdelegate" Sep 01 18:44:22 volumion2 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) Sep 01 18:44:22 volumion2 systemd[1]: Detected architecture arm64. Sep 01 18:44:22 volumion2 systemd[1]: Set hostname to . Sep 01 18:44:22 volumion2 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. Sep 01 18:44:22 volumion2 systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Sep 01 18:44:22 volumion2 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. Sep 01 18:44:22 volumion2 systemd[1]: Listening on udev Control Socket. Sep 01 18:44:22 volumion2 systemd[1]: Listening on udev Kernel Socket. Sep 01 18:44:22 volumion2 systemd[1]: Listening on fsck to fsckd communication Socket. Sep 01 18:44:22 volumion2 systemd[1]: Created slice system-systemd\x2dfsck.slice. Sep 01 18:44:22 volumion2 kernel: RPC: Registered named UNIX socket transport module. Sep 01 18:44:22 volumion2 kernel: RPC: Registered udp transport module. Sep 01 18:44:22 volumion2 kernel: RPC: Registered tcp transport module. Sep 01 18:44:22 volumion2 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 01 18:44:22 volumion2 kernel: Removed cp15_barrier emulation handler Sep 01 18:44:22 volumion2 kernel: Enabled cp15_barrier support Sep 01 18:44:22 volumion2 systemd-journald[2116]: Journal started Sep 01 18:44:22 volumion2 systemd-journald[2116]: Runtime journal (/run/log/journal/a32d29ef605ad586c40fd8f765bac4ee) is 3.7M, max 30.0M, 26.2M free. Sep 01 18:44:22 volumion2 fake-hwclock[2120]: Mon Sep 1 16:44:22 UTC 2025 Sep 01 18:44:22 volumion2 systemd[1]: Started Apply Kernel Variables. Sep 01 18:44:22 volumion2 systemd[1]: Started Create System Users. Sep 01 18:44:22 volumion2 systemd[1]: Started Load/Save Random Seed. Sep 01 18:44:22 volumion2 systemd[1]: Starting Create Static Device Nodes in /dev... Sep 01 18:44:22 volumion2 systemd[1]: Started udev Coldplug all Devices. Sep 01 18:44:22 volumion2 systemd[1]: Started Create Static Device Nodes in /dev. Sep 01 18:44:22 volumion2 systemd[1]: Starting udev Kernel Device Manager... Sep 01 18:44:22 volumion2 systemd[1]: Reached target Local File Systems (Pre). Sep 01 18:44:22 volumion2 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 01 18:44:22 volumion2 systemd[1]: Mounting /var/log... Sep 01 18:44:22 volumion2 systemd[1]: Mounting /var/spool/cups... Sep 01 18:44:22 volumion2 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 01 18:44:22 volumion2 systemd[1]: Mounting /tmp... Sep 01 18:44:22 volumion2 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 01 18:44:22 volumion2 systemd-udevd[2144]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 01 18:44:22 volumion2 systemd[1]: Mounted /var/log. Sep 01 18:44:22 volumion2 systemd[1]: Mounted /var/spool/cups. Sep 01 18:44:22 volumion2 systemd[1]: Mounting /var/spool/cups/tmp... Sep 01 18:44:22 volumion2 systemd[1]: Starting Flush Journal to Persistent Storage... Sep 01 18:44:22 volumion2 systemd[1]: Started udev Kernel Device Manager. Sep 01 18:44:22 volumion2 systemd[1]: Mounted /tmp. Sep 01 18:44:22 volumion2 systemd[1]: Starting Show Plymouth Boot Screen... Sep 01 18:44:22 volumion2 systemd[1]: Started Helper to synchronize boot up for ifupdown. Sep 01 18:44:22 volumion2 systemd[1]: Mounted /var/spool/cups/tmp. Sep 01 18:44:22 volumion2 systemd-journald[2116]: Runtime journal (/run/log/journal/a32d29ef605ad586c40fd8f765bac4ee) is 3.7M, max 30.0M, 26.2M free. Sep 01 18:44:22 volumion2 systemd[1]: Started Flush Journal to Persistent Storage. Sep 01 18:44:22 volumion2 systemd[1]: plymouth-start.service: Succeeded. Sep 01 18:44:22 volumion2 systemd[1]: Started Show Plymouth Boot Screen. Sep 01 18:44:22 volumion2 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Local Encrypted Volumes. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Paths. Sep 01 18:44:22 volumion2 systemd-udevd[2160]: Using default interface naming scheme 'v240'. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Starting Show Plymouth Boot Screen... Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Sep 01 18:44:22 volumion2 systemd-udevd[2160]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 01 18:44:22 volumion2 systemd-udevd[2160]: link_config: could not get ethtool features for eth0 Sep 01 18:44:22 volumion2 systemd-udevd[2160]: Could not set offload features of eth0: Device or resource busy Sep 01 18:44:22 volumion2 systemd[1]: Found device /dev/ttyS0. Sep 01 18:44:22 volumion2 kernel: rtc-pcf8563 1-0051: rtc core: registered rtc-pcf8563 as rtc0 Sep 01 18:44:22 volumion2 systemd-udevd[2167]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 01 18:44:22 volumion2 systemd[1]: plymouth-start.service: Succeeded. Sep 01 18:44:22 volumion2 systemd[1]: Started Show Plymouth Boot Screen. Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: ionvideo open Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close!!!! Sep 01 18:44:22 volumion2 kernel: ionvid: dbg: vidioc_close Sep 01 18:44:22 volumion2 systemd-udevd[2168]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 18:44:22 volumion2 systemd[1]: Found device /dev/disk/by-uuid/5671-75AA. Sep 01 18:44:22 volumion2 systemd[1]: Starting File System Check on /dev/disk/by-uuid/5671-75AA... Sep 01 18:44:22 volumion2 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 01 18:44:22 volumion2 systemd-udevd[2180]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 18:44:22 volumion2 kernel: out of value, fixed it Sep 01 18:44:22 volumion2 kernel: id=0 set inskew=0 Sep 01 18:44:22 volumion2 kernel: AED req_sel0 module:TDMOUT_B Sep 01 18:44:22 volumion2 kernel: spk_mute_set: mute flag = 0 Sep 01 18:44:22 volumion2 systemd-udevd[2164]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 18:44:22 volumion2 systemd[1]: Started File System Check Daemon to report status. Sep 01 18:44:22 volumion2 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Sep 01 18:44:22 volumion2 kernel: vout: aml_tvout_mode_work: monitor_timeout Sep 01 18:44:22 volumion2 systemd-udevd[2184]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 18:44:22 volumion2 systemd-fsck[2242]: fsck.fat 4.1 (2017-01-24) Sep 01 18:44:22 volumion2 systemd-fsck[2242]: /dev/mmcblk1p1: 37 files, 11099/30651 clusters Sep 01 18:44:22 volumion2 systemd[1]: Started File System Check on /dev/disk/by-uuid/5671-75AA. Sep 01 18:44:22 volumion2 systemd[1]: Mounting /boot... Sep 01 18:44:22 volumion2 systemd[1]: Mounted /boot. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Local File Systems. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Starting Create Volatile Files and Directories... Sep 01 18:44:22 volumion2 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 01 18:44:22 volumion2 systemd[1]: Starting Raise network interfaces... Sep 01 18:44:22 volumion2 systemd[1]: Starting Preprocess NFS configuration... Sep 01 18:44:22 volumion2 systemd[1]: Started ifup for eth0. Sep 01 18:44:22 volumion2 systemd[1]: Received SIGRTMIN+20 from PID 2014 (plymouthd). Sep 01 18:44:22 volumion2 systemd[1]: plymouth-read-write.service: Succeeded. Sep 01 18:44:22 volumion2 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 01 18:44:22 volumion2 systemd[1]: nfs-config.service: Succeeded. Sep 01 18:44:22 volumion2 systemd[1]: Started Preprocess NFS configuration. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Reached target NFS client services. Sep 01 18:44:22 volumion2 systemd[1]: Started Create Volatile Files and Directories. Sep 01 18:44:22 volumion2 systemd[1]: Starting RPC bind portmap service... Sep 01 18:44:22 volumion2 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 01 18:44:22 volumion2 ifup[2250]: command failed: No such device (-19) Sep 01 18:44:22 volumion2 sh[2252]: command failed: No such device (-19) Sep 01 18:44:22 volumion2 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 01 18:44:22 volumion2 systemd[1]: Started RPC bind portmap service. Sep 01 18:44:22 volumion2 systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 01 18:44:22 volumion2 systemd[1]: Reached target System Initialization. Sep 01 18:44:22 volumion2 systemd[1]: Listening on lircd.socket. Sep 01 18:44:22 volumion2 systemd[1]: Started Daily apt download activities. Sep 01 18:44:22 volumion2 systemd[1]: Started Daily apt upgrade and clean activities. Sep 01 18:44:22 volumion2 systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Timers. Sep 01 18:44:22 volumion2 systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 01 18:44:22 volumion2 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 01 18:44:22 volumion2 systemd[1]: Listening on triggerhappy.socket. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Sockets. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Basic System. Sep 01 18:44:22 volumion2 systemd[1]: Starting Login Service... Sep 01 18:44:22 volumion2 systemd[1]: Started volumio-remote-updater.service. Sep 01 18:44:22 volumion2 systemd[1]: Started D-Bus System Message Bus. Sep 01 18:44:22 volumion2 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 01 18:44:22 volumion2 systemd[1]: Started Volumio Log Rotation Service. Sep 01 18:44:22 volumion2 systemd[1]: Started UPnP Renderer front-end to MPD. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 01 18:44:22 volumion2 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 01 18:44:22 volumion2 kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 01 18:44:22 volumion2 systemd[1]: Starting triggerhappy global hotkey daemon... Sep 01 18:44:22 volumion2 systemd[1]: Started Manage Sound Card State (restore and store). Sep 01 18:44:22 volumion2 systemd[1]: Started Volumio Iptables Module. Sep 01 18:44:22 volumion2 systemd[1]: Started MPD Monitor Service. Sep 01 18:44:22 volumion2 systemd-logind[2291]: Watching system buttons on /dev/input/event2 (Logitech K400 Plus) Sep 01 18:44:22 volumion2 systemd-logind[2291]: Watching system buttons on /dev/input/event0 (gpio_keypad) Sep 01 18:44:22 volumion2 systemd-logind[2291]: Watching system buttons on /dev/input/event1 (cec_input) Sep 01 18:44:22 volumion2 systemd-logind[2291]: New seat seat0. Sep 01 18:44:22 volumion2 systemd[1]: Starting Save/Restore Sound Card State... Sep 01 18:44:22 volumion2 thd[2309]: Unable to parse trigger line: Sep 01 18:44:22 volumion2 thd[2309]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 01 18:44:22 volumion2 thd[2309]: Unable to parse trigger line: Sep 01 18:44:22 volumion2 thd[2309]: Unable to parse trigger line: Sep 01 18:44:22 volumion2 thd[2309]: Found socket passed from systemd Sep 01 18:44:22 volumion2 alsactl[2310]: alsactl 1.1.8 daemon started Sep 01 18:44:22 volumion2 avahi-daemon[2298]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Sep 01 18:44:22 volumion2 avahi-daemon[2298]: Successfully dropped root privileges. Sep 01 18:44:22 volumion2 avahi-daemon[2298]: avahi-daemon 0.7 starting up. Sep 01 18:44:22 volumion2 mpd_monitor.sh[2313]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 18:44:22 volumion2 systemd[1]: Starting Volumio Time Update Utility... Sep 01 18:44:22 volumion2 ifup[2250]: command failed: No such device (-19) Sep 01 18:44:22 volumion2 systemd[1]: Starting DHCP Client Daemon... Sep 01 18:44:22 volumion2 systemd[1]: Starting Wireless Services... Sep 01 18:44:22 volumion2 systemd[1]: Starting WPA supplicant... Sep 01 18:44:22 volumion2 systemd[1]: Reached target Remote File Systems (Pre). Sep 01 18:44:22 volumion2 systemd[1]: Reached target Remote File Systems. Sep 01 18:44:22 volumion2 alsactl[2310]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Sep 01 18:44:22 volumion2 alsactl[2310]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Sep 01 18:44:22 volumion2 alsactl[2310]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Sep 01 18:44:22 volumion2 kernel: out of value, fixed it Sep 01 18:44:22 volumion2 kernel: id=0 set inskew=0 Sep 01 18:44:22 volumion2 kernel: AED req_sel0 module:TDMOUT_B Sep 01 18:44:22 volumion2 kernel: spk_mute_set: mute flag = 0 Sep 01 18:44:22 volumion2 alsactl[2315]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Sep 01 18:44:22 volumion2 alsactl[2315]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Sep 01 18:44:22 volumion2 alsactl[2315]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Sep 01 18:44:22 volumion2 kernel: out of value, fixed it Sep 01 18:44:22 volumion2 kernel: id=0 set inskew=0 Sep 01 18:44:22 volumion2 kernel: AED req_sel0 module:TDMOUT_B Sep 01 18:44:22 volumion2 kernel: spk_mute_set: mute flag = 0 Sep 01 18:44:22 volumion2 volumio-time-update[2317]: volumio-time-update-util: Fetching time from Volumio... Sep 01 18:44:22 volumion2 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 01 18:44:22 volumion2 systemd[1]: Reached target RPC Port Mapper. Sep 01 18:44:22 volumion2 systemd[1]: Started triggerhappy global hotkey daemon. Sep 01 18:44:22 volumion2 systemd[1]: Started Save/Restore Sound Card State. Sep 01 18:44:22 volumion2 systemd[1]: Reached target Sound Card. Sep 01 18:44:23 volumion2 mpd_monitor.sh[2313]: MPD error: Connection refused Sep 01 18:44:23 volumion2 mpd_monitor.sh[2313]: MPD Monitor Service: MPD Appears to be inactive, restarting Sep 01 18:44:23 volumion2 mpd_monitor.sh[2313]: mpd: no process found Sep 01 18:44:23 volumion2 systemd[1]: Started Login Service. Sep 01 18:44:23 volumion2 avahi-daemon[2298]: Successfully called chroot(). Sep 01 18:44:23 volumion2 avahi-daemon[2298]: Successfully dropped remaining capabilities. Sep 01 18:44:23 volumion2 avahi-daemon[2298]: Loading service file /services/volumio.service. Sep 01 18:44:23 volumion2 dhcpcd[2322]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Sep 01 18:44:23 volumion2 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 01 18:44:23 volumion2 kernel: nf_tables: (c) 2007-2009 Patrick McHardy Sep 01 18:44:23 volumion2 systemd[1]: Listening on mpd.socket. Sep 01 18:44:23 volumion2 dhcpcd[2322]: no interfaces have a carrier Sep 01 18:44:23 volumion2 dhcpcd[2322]: no interfaces have a carrier Sep 01 18:44:23 volumion2 dhcpcd[2322]: forked to background, child pid 2369 Sep 01 18:44:23 volumion2 dhcpcd[2322]: forked to background, child pid 2369 Sep 01 18:44:23 volumion2 systemd[1]: Started DHCP Client Daemon. Sep 01 18:44:23 volumion2 kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Sep 01 18:44:23 volumion2 kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Sep 01 18:44:23 volumion2 kernel: meson6-dwmac ff3f0000.ethernet eth0: fail to init PTP. Sep 01 18:44:23 volumion2 sh[2252]: eth0: waiting for carrier Sep 01 18:44:23 volumion2 sh[2252]: eth0: carrier acquired Sep 01 18:44:23 volumion2 avahi-daemon[2298]: Network interface enumeration completed. Sep 01 18:44:23 volumion2 dhcpcd[2286]: eth0: waiting for carrier Sep 01 18:44:23 volumion2 dhcpcd[2286]: eth0: carrier acquired Sep 01 18:44:23 volumion2 avahi-daemon[2298]: Server startup complete. Host name is volumion2.local. Local service cookie is 1254471422. Sep 01 18:44:23 volumion2 avahi-daemon[2298]: Service "VolumioN2+" (/services/volumio.service) successfully established. Sep 01 18:44:23 volumion2 dhcpcd[2369]: eth0: waiting for carrier Sep 01 18:44:23 volumion2 dhcpcd[2369]: eth0: carrier acquired Sep 01 18:44:23 volumion2 dhcpcd[2369]: DUID 00:01:00:01:2d:4d:81:dc:00:1e:06:43:c3:d7 Sep 01 18:44:23 volumion2 dhcpcd[2369]: eth0: IAID 06:43:c3:d7 Sep 01 18:44:23 volumion2 systemd[1]: Started WPA supplicant. Sep 01 18:44:23 volumion2 dhcpcd[2369]: eth0: adding address fe80::5ef:3e5a:d06d:834b Sep 01 18:44:23 volumion2 wpa_supplicant[2325]: Successfully initialized wpa_supplicant Sep 01 18:44:23 volumion2 dhcpcd[2369]: ipv6_addaddr1: Permission denied Sep 01 18:44:23 volumion2 dhcpcd[2369]: eth0: carrier lost Sep 01 18:44:23 volumion2 sh[2252]: DUID 00:01:00:01:2d:4d:81:dc:00:1e:06:43:c3:d7 Sep 01 18:44:23 volumion2 sh[2252]: eth0: IAID 06:43:c3:d7 Sep 01 18:44:23 volumion2 sh[2252]: eth0: adding address fe80::5ef:3e5a:d06d:834b Sep 01 18:44:23 volumion2 sh[2252]: ipv6_addaddr1: Permission denied Sep 01 18:44:23 volumion2 sh[2252]: eth0: carrier lost Sep 01 18:44:23 volumion2 dhcpcd[2286]: DUID 00:01:00:01:2d:4d:81:dc:00:1e:06:43:c3:d7 Sep 01 18:44:23 volumion2 dhcpcd[2286]: eth0: IAID 06:43:c3:d7 Sep 01 18:44:23 volumion2 dhcpcd[2286]: eth0: adding address fe80::5ef:3e5a:d06d:834b Sep 01 18:44:23 volumion2 dhcpcd[2286]: ipv6_addaddr1: Permission denied Sep 01 18:44:23 volumion2 dhcpcd[2286]: eth0: carrier lost Sep 01 18:44:23 volumion2 volumio-remote-updater[2292]: Error: No active session Sep 01 18:44:23 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:23] [info] asio async_connect error: system:111 (Connection refused) Sep 01 18:44:23 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 01 18:44:23 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:23] [error] handle_connect error: Underlying Transport Error Sep 01 18:44:23 volumion2 volumio-time-update[2317]: volumio-time-update-util: Date not found in response Sep 01 18:44:23 volumion2 volumio-time-update[2317]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 18:44:23 volumion2 sh[2252]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Sep 01 18:44:23 volumion2 volumio[2302]: Could not open config: /tmp/upmpdcli.conf Sep 01 18:44:23 volumion2 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 01 18:44:23 volumion2 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 01 18:44:23 volumion2 systemd[1]: Started Raise network interfaces. Sep 01 18:44:23 volumion2 systemd[1]: Reached target Network. Sep 01 18:44:23 volumion2 systemd[1]: Starting Permit User Sessions... Sep 01 18:44:23 volumion2 loadcpufreq[2329]: Loading cpufreq kernel modules...done (none). Sep 01 18:44:23 volumion2 systemd[1]: Starting Network Time Service... Sep 01 18:44:23 volumion2 systemd[1]: Starting Music Player Daemon... Sep 01 18:44:23 volumion2 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 01 18:44:23 volumion2 systemd[1]: Starting OpenBSD Secure Shell server... Sep 01 18:44:23 volumion2 systemd[1]: Reached target Network is Online. Sep 01 18:44:23 volumion2 systemd[1]: Starting Daily apt download activities... Sep 01 18:44:23 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:23 volumion2 systemd[1]: Starting LSB: Brings up/down network automatically... Sep 01 18:44:23 volumion2 systemd[1]: Starting Samba NMB Daemon... Sep 01 18:44:23 volumion2 systemd[1]: Starting /etc/rc.local Compatibility... Sep 01 18:44:23 volumion2 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 01 18:44:23 volumion2 systemd[1]: Started Permit User Sessions. Sep 01 18:44:23 volumion2 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 01 18:44:23 volumion2 ntpd[2473]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 18:44:23 volumion2 ntpd[2473]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 01 18:44:23 volumion2 systemd[1]: Started Network Time Service. Sep 01 18:44:23 volumion2 sudo[2474]: root : unable to resolve host volumion2 Sep 01 18:44:23 volumion2 sudo[2474]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:23 volumion2 sudo[2474]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:23 volumion2 ntpd[2492]: proto: precision = 0.458 usec (-21) Sep 01 18:44:23 volumion2 systemd[1]: Started /etc/rc.local Compatibility. Sep 01 18:44:23 volumion2 ntpd[2492]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 18:44:23 volumion2 ntpd[2492]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 18:44:23 volumion2 ntpd[2492]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 979 days ago Sep 01 18:44:23 volumion2 ntpd[2492]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 18:44:23 volumion2 ntpd[2492]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 18:44:23 volumion2 ntpd[2492]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 18:44:23 volumion2 ntpd[2492]: Listening on routing socket on fd #19 for interface updates Sep 01 18:44:23 volumion2 ntpd[2492]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 18:44:23 volumion2 ntpd[2492]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 18:44:23 volumion2 sudo[2474]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 18:44:23 volumion2 sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:23 volumion2 sudo[2474]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 01 18:44:23 volumion2 sudo[2474]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:23 volumion2 systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 01 18:44:23 volumion2 systemd[1]: Starting Hold until boot process finishes up... Sep 01 18:44:23 volumion2 systemd[1]: Received SIGRTMIN+21 from PID 2014 (plymouthd). Sep 01 18:44:23 volumion2 sshd[2504]: Server listening on 0.0.0.0 port 22. Sep 01 18:44:23 volumion2 sshd[2504]: Server listening on :: port 22. Sep 01 18:44:23 volumion2 systemd[1]: Started OpenBSD Secure Shell server. Sep 01 18:44:23 volumion2 systemd[1]: Received SIGRTMIN+21 from PID 2014 (plymouthd). Sep 01 18:44:23 volumion2 systemd[1]: plymouth-quit.service: Succeeded. Sep 01 18:44:23 volumion2 systemd[1]: Started Terminate Plymouth Boot Screen. Sep 01 18:44:23 volumion2 systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 01 18:44:23 volumion2 systemd[1]: Started Hold until boot process finishes up. Sep 01 18:44:23 volumion2 kernel: fb: osd_release now.index=3,open_count=1 Sep 01 18:44:23 volumion2 kernel: fb: osd_release now.index=2,open_count=1 Sep 01 18:44:23 volumion2 kernel: fb: osd_release now.index=1,open_count=1 Sep 01 18:44:23 volumion2 ifplugd[2481]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Sep 01 18:44:23 volumion2 systemd[1]: Started Getty on tty1. Sep 01 18:44:23 volumion2 systemd[1]: Started Serial Getty on ttyS0. Sep 01 18:44:23 volumion2 systemd[1]: Reached target Login Prompts. Sep 01 18:44:23 volumion2 ifplugd(eth0)[2535]: ifplugd 0.28 initializing. Sep 01 18:44:23 volumion2 ifplugd(eth0)[2535]: Using interface eth0/00:1E:06:43:C3:D7 with driver (version: Jan_2016) Sep 01 18:44:23 volumion2 ifplugd(eth0)[2535]: Using detection mode: SIOCETHTOOL Sep 01 18:44:23 volumion2 ifplugd(eth0)[2535]: Initialization complete, link beat not detected. Sep 01 18:44:23 volumion2 cpufrequtils[2486]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...CPU4...CPU5...done. Sep 01 18:44:23 volumion2 haveged[2272]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Sep 01 18:44:23 volumion2 haveged[2272]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Sep 01 18:44:23 volumion2 haveged[2272]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99885 Sep 01 18:44:23 volumion2 haveged[2272]: haveged: fills: 0, generated: 0 Sep 01 18:44:23 volumion2 systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 01 18:44:23 volumion2 ifplugd[2481]: start eth0...done. Sep 01 18:44:23 volumion2 systemd[1]: Started LSB: Brings up/down network automatically. Sep 01 18:44:23 volumion2 nmbd[2494]: [2025/09/01 18:44:23.895972, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 01 18:44:23 volumion2 nmbd[2494]: started asyncdns process 2564 Sep 01 18:44:23 volumion2 nmbd[2494]: [2025/09/01 18:44:23.897351, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 01 18:44:23 volumion2 nmbd[2494]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 01 18:44:23 volumion2 nmbd[2494]: [2025/09/01 18:44:23.897450, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 01 18:44:23 volumion2 nmbd[2494]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 01 18:44:24 volumion2 systemd[1]: apt-daily.service: Succeeded. Sep 01 18:44:24 volumion2 systemd[1]: Started Daily apt download activities. Sep 01 18:44:24 volumion2 systemd[1]: iptables.service: Succeeded. Sep 01 18:44:24 volumion2 wireless.js[2324]: WIRELESS: No wireless interface, exiting Sep 01 18:44:24 volumion2 iw[2591]: command failed: No such device (-19) Sep 01 18:44:24 volumion2 iwconfig[2594]: Error for wireless request "Set Power Management" (8B2C) : Sep 01 18:44:24 volumion2 iwconfig[2594]: SET failed on device wlan0 ; No such device. Sep 01 18:44:24 volumion2 systemd[1]: wireless.service: Succeeded. Sep 01 18:44:24 volumion2 systemd[1]: Started Wireless Services. Sep 01 18:44:24 volumion2 systemd[1]: Started Volumio Backend Module. Sep 01 18:44:24 volumion2 systemd[1]: Started Volumio Cpu Tweaker. Sep 01 18:44:24 volumion2 volumio-cpu-tweak[2598]: Setting RT Priority for mpd Sep 01 18:44:24 volumion2 volumio-cpu-tweak[2598]: Setting MPD Affinity Sep 01 18:44:24 volumion2 volumio-cpu-tweak[2598]: pid 2510's current affinity mask: 3f Sep 01 18:44:24 volumion2 volumio-cpu-tweak[2598]: pid 2510's new affinity mask: 3 Sep 01 18:44:24 volumion2 volumio-cpu-tweak[2598]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 01 18:44:24 volumion2 volumio-cpu-tweak[2598]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 01 18:44:24 volumion2 systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 01 18:44:24 volumion2 ntpd[2492]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 01 18:44:25 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:25 volumion2 volumio[2597]: info: ----- Volumio3 ---- Sep 01 18:44:25 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:25 volumion2 volumio[2597]: info: ----- System startup ---- Sep 01 18:44:25 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:25 volumion2 ntpd[2492]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 01 18:44:25 volumion2 volumio[2597]: info: MYVOLUMIO Environment detected Sep 01 18:44:25 volumion2 mpd[2510]: Sep 01 18:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 18:44:25 volumion2 volumio[2597]: info: Plugin folders cleanup Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning into folder /volumio/app/plugins/ Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category audio_interface Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category miscellanea Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category music_service Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category plugins.json Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category system_controller Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category user_interface Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning into folder /data/plugins/ Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category audio_interface Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category music_service Sep 01 18:44:25 volumion2 volumio[2597]: info: Scanning category system_hardware Sep 01 18:44:25 volumion2 volumio[2597]: info: Plugin folders cleanup completed Sep 01 18:44:25 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:25 volumion2 volumio[2597]: info: ----- Core plugins startup ---- Sep 01 18:44:25 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:25 volumion2 volumio[2597]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 18:44:25 volumion2 volumio[2597]: info: Adding plugin upnp to MyMusic Plugins Sep 01 18:44:25 volumion2 volumio[2597]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 18:44:25 volumion2 volumio[2597]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 18:44:25 volumion2 volumio[2597]: info: Loading plugins from folder /data/plugins/ Sep 01 18:44:25 volumion2 volumio[2597]: info: Loading plugin "system"... Sep 01 18:44:25 volumion2 systemd[1]: Started Music Player Daemon. Sep 01 18:44:25 volumion2 mpd_monitor.sh[2313]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 01 18:44:25 volumion2 volumio[2597]: info: Loading plugin "appearance"... Sep 01 18:44:26 volumion2 ntpd[2492]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "network"... Sep 01 18:44:26 volumion2 volumio[2597]: info: Refreshing Cached IP Addresses Sep 01 18:44:26 volumion2 sudo[2632]: volumio : unable to resolve host volumion2 Sep 01 18:44:26 volumion2 sudo[2632]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:26 volumion2 sudo[2632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:44:26 volumion2 sudo[2632]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:26 volumion2 sudo[2632]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:26 volumion2 sudo[2635]: volumio : unable to resolve host volumion2 Sep 01 18:44:26 volumion2 sudo[2635]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:26 volumion2 sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:44:26 volumion2 sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "services"... Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "alsa_controller"... Sep 01 18:44:26 volumion2 sudo[2641]: volumio : unable to resolve host volumion2 Sep 01 18:44:26 volumion2 sudo[2641]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:26 volumion2 sudo[2635]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:26 volumion2 sudo[2641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 18:44:26 volumion2 sudo[2641]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:26 volumion2 sudo[2641]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:26 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "wizard"... Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "networkfs"... Sep 01 18:44:26 volumion2 sudo[2664]: volumio : unable to resolve host volumion2 Sep 01 18:44:26 volumion2 sudo[2664]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:26 volumion2 sudo[2664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=maslako,password=Pantera27!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.1.251/Muzyka /mnt/NAS/DELL Sep 01 18:44:26 volumion2 sudo[2664]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:26 volumion2 volumio[2597]: info: Starting Udev Watcher for removable devices Sep 01 18:44:26 volumion2 volumio[2597]: info: Ignoring mount for partition: boot Sep 01 18:44:26 volumion2 volumio[2597]: info: Ignoring mount for partition: volumio Sep 01 18:44:26 volumion2 volumio[2597]: info: Ignoring mount for partition: volumio_data Sep 01 18:44:26 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "volumio_command_line_client"... Sep 01 18:44:26 volumion2 sudo[2667]: volumio : unable to resolve host volumion2 Sep 01 18:44:26 volumion2 sudo[2667]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:26 volumion2 sudo[2667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.247:/Pobrane/Muzyka /mnt/NAS/OMV Sep 01 18:44:26 volumion2 sudo[2667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "upnp"... Sep 01 18:44:26 volumion2 sudo[2670]: volumio : unable to resolve host volumion2 Sep 01 18:44:26 volumion2 sudo[2670]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:26 volumion2 sudo[2670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=maslako,password=Pantera27!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.1.251/Muzyka /mnt/NAS/Muzyka Sep 01 18:44:26 volumion2 sudo[2670]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:26 volumion2 volumio[2597]: info: [1756745066836] Starting Upmpd Daemon Sep 01 18:44:26 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "my_music"... Sep 01 18:44:26 volumion2 volumio[2597]: info: Loading plugin "mpd"... Sep 01 18:44:26 volumion2 kernel: FS-Cache: Loaded Sep 01 18:44:26 volumion2 kernel: FS-Cache: Netfs 'cifs' registered for caching Sep 01 18:44:26 volumion2 kernel: Key type cifs.spnego registered Sep 01 18:44:26 volumion2 kernel: Key type cifs.idmap registered Sep 01 18:44:26 volumion2 kernel: FS-Cache: Netfs 'nfs' registered for caching Sep 01 18:44:26 volumion2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Sep 01 18:44:26 volumion2 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Sep 01 18:44:26 volumion2 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Sep 01 18:44:26 volumion2 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Sep 01 18:44:26 volumion2 sudo[2670]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:26 volumion2 sudo[2664]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:26 volumion2 kernel: NFS: Registering the id_resolver key type Sep 01 18:44:26 volumion2 kernel: Key type id_resolver registered Sep 01 18:44:26 volumion2 kernel: Key type id_legacy registered Sep 01 18:44:26 volumion2 sudo[2667]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:27 volumion2 volumio[2597]: info: Plugin upnp_browser is not enabled Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "alarm-clock"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "airplay_emulation"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Starting Shairport Sync Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "last_100"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "webradio"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "i2s_dacs"... Sep 01 18:44:27 volumion2 volumio[2597]: info: I2S DAC not set, start Auto-detection Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "volumiodiscovery"... Sep 01 18:44:27 volumion2 sh[2252]: eth0: carrier acquired Sep 01 18:44:27 volumion2 dhcpcd[2369]: eth0: carrier acquired Sep 01 18:44:27 volumion2 dhcpcd[2286]: eth0: carrier acquired Sep 01 18:44:27 volumion2 kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 01 18:44:27 volumion2 sh[2252]: eth0: IAID 06:43:c3:d7 Sep 01 18:44:27 volumion2 dhcpcd[2369]: eth0: IAID 06:43:c3:d7 Sep 01 18:44:27 volumion2 dhcpcd[2286]: eth0: IAID 06:43:c3:d7 Sep 01 18:44:27 volumion2 volumio[2597]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 18:44:27 volumion2 volumio[2597]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:44:27 volumion2 volumio[2597]: *** WARNING *** For more information see Sep 01 18:44:27 volumion2 volumio[2597]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 18:44:27 volumion2 volumio[2597]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:44:27 volumion2 node[2597]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 18:44:27 volumion2 volumio[2597]: *** WARNING *** For more information see Sep 01 18:44:27 volumion2 node[2597]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:44:27 volumion2 node[2597]: *** WARNING *** For more information see Sep 01 18:44:27 volumion2 node[2597]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 18:44:27 volumion2 node[2597]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:44:27 volumion2 node[2597]: *** WARNING *** For more information see Sep 01 18:44:27 volumion2 volumio[2597]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 18:44:27 volumion2 volumio[2597]: info: Discovery: Started advertising with name: VolumioN2+ Sep 01 18:44:27 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "outputs"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "albumart"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Plugin example_plugin is not enabled Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "inputs"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "updater_comm"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Plugin mpdemulation is not enabled Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "rest_api"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "websocket"... Sep 01 18:44:27 volumion2 volumio[2597]: info: Starting Socket.io Server version 2.3.0 Sep 01 18:44:27 volumion2 volumio[2597]: info: Loading plugin "fusiondsp"... Sep 01 18:44:27 volumion2 ntpd[2492]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 01 18:44:27 volumion2 volumio[2597]: Forking 5 albumart workers Sep 01 18:44:27 volumion2 sh[2252]: eth0: soliciting an IPv6 router Sep 01 18:44:27 volumion2 dhcpcd[2286]: eth0: soliciting an IPv6 router Sep 01 18:44:27 volumion2 dhcpcd[2369]: eth0: soliciting a DHCP lease Sep 01 18:44:27 volumion2 dhcpcd[2369]: eth0: offered 192.168.1.237 from 192.168.1.1 Sep 01 18:44:27 volumion2 dhcpcd[2369]: eth0: probing address 192.168.1.237/24 Sep 01 18:44:27 volumion2 dhcpcd[2369]: eth0: soliciting an IPv6 router Sep 01 18:44:27 volumion2 ifplugd(eth0)[2535]: Link beat detected. Sep 01 18:44:27 volumion2 sh[2252]: eth0: soliciting a DHCP lease Sep 01 18:44:27 volumion2 dhcpcd[2286]: eth0: soliciting a DHCP lease Sep 01 18:44:27 volumion2 sh[2252]: eth0: offered 192.168.1.237 from 192.168.1.1 Sep 01 18:44:27 volumion2 dhcpcd[2286]: eth0: offered 192.168.1.237 from 192.168.1.1 Sep 01 18:44:27 volumion2 sh[2252]: eth0: probing address 192.168.1.237/24 Sep 01 18:44:27 volumion2 dhcpcd[2286]: eth0: probing address 192.168.1.237/24 Sep 01 18:44:27 volumion2 ifplugd(eth0)[2535]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 01 18:44:27 volumion2 volumio[2597]: info: Applying required configuration parameters for plugin fusiondsp Sep 01 18:44:27 volumion2 volumio[2597]: Starting albumart workers Sep 01 18:44:27 volumion2 volumio[2597]: Starting albumart workers Sep 01 18:44:27 volumion2 ifplugd(eth0)[2535]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Sep 01 18:44:27 volumion2 volumio[2597]: Starting albumart workers Sep 01 18:44:28 volumion2 volumio[2597]: Starting albumart workers Sep 01 18:44:28 volumion2 volumio[2597]: info: Loading plugin "rad357pl"... Sep 01 18:44:28 volumion2 volumio[2597]: Starting albumart workers Sep 01 18:44:28 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:28] [connect] Successful connection Sep 01 18:44:28 volumion2 volumio-time-update[2317]: volumio-time-update-util: Fetching time from Volumio... Sep 01 18:44:28 volumion2 volumio-time-update[2317]: volumio-time-update-util: Date not found in response Sep 01 18:44:28 volumion2 volumio-time-update[2317]: volumio-time-update-util: Retrying in 5 seconds... Sep 01 18:44:28 volumion2 volumio[2597]: info: Applying required configuration parameters for plugin rad357pl Sep 01 18:44:28 volumion2 volumio[2597]: info: [1756745068433] [rad357pl] API delay: 5 Sep 01 18:44:28 volumion2 volumio[2597]: info: Loading plugin "radio_paradise"... Sep 01 18:44:28 volumion2 volumio[2597]: info: Applying required configuration parameters for plugin radio_paradise Sep 01 18:44:28 volumion2 volumio[2597]: info: [1756745068586] [RadioParadise] API delay: 5 Sep 01 18:44:28 volumion2 volumio[2597]: info: Loading plugin "ir_controller"... Sep 01 18:44:28 volumion2 volumio[2597]: info: Applying required configuration parameters for plugin ir_controller Sep 01 18:44:28 volumion2 volumio[2597]: info: Loading i18n strings for locale pl Sep 01 18:44:28 volumion2 volumio[2597]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 01 18:44:28 volumion2 volumio[2597]: Updating browse sources language Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::initPlayerControls Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: Express server listening on port 3000 Sep 01 18:44:28 volumion2 volumio[2597]: [Metrics] WebUI: 3s 888.31ms Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreStateMachine::resetVolumioState Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreStateMachine::getcurrentVolume Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 18:44:28 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:28 volumion2 volumio[2597]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 01 18:44:28 volumion2 volumio[2597]: info: Completed loading Core Plugins Sep 01 18:44:28 volumion2 volumio[2597]: info: Preparing to generate the ALSA configuration file Sep 01 18:44:28 volumion2 volumio[2597]: info: Volumio Network Manager: Network status updated: 0 Sep 01 18:44:28 volumion2 volumio[2597]: info: Cannot mount NAS Muzyka at system boot, trial number 1 ,retrying in 5 seconds Sep 01 18:44:28 volumion2 volumio[2597]: info: Cannot mount NAS OMV at system boot, trial number 1 ,retrying in 5 seconds Sep 01 18:44:28 volumion2 volumio[2597]: info: Cannot mount NAS DELL at system boot, trial number 1 ,retrying in 5 seconds Sep 01 18:44:28 volumion2 volumio[2597]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 01 18:44:28 volumion2 volumio[2597]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:28 volumion2 volumio[2597]: wlan0 Interface doesn't support scanning. Sep 01 18:44:28 volumion2 volumio[2597]: info: Cannot use regular scanning, forcing with ap-force Sep 01 18:44:28 volumion2 sudo[2789]: volumio : unable to resolve host volumion2 Sep 01 18:44:28 volumion2 sudo[2789]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:28 volumion2 sudo[2789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 01 18:44:28 volumion2 sudo[2789]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:28 volumion2 sudo[2789]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:28 volumion2 volumio[2597]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:28 volumion2 volumio[2597]: command failed: No such device (-19) Sep 01 18:44:28 volumion2 volumio[2597]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 01 18:44:28 volumion2 volumio[2597]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:28 volumion2 volumio[2597]: command failed: No such device (-19) Sep 01 18:44:28 volumion2 volumio[2597]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 01 18:44:28 volumion2 volumio[2597]: info: Reading ALSA contributions from plugins. Sep 01 18:44:29 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756745068 101 Sep 01 18:44:29 volumion2 volumio[2597]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 01 18:44:29 volumion2 volumio[2597]: Unable to parse: Sep 01 18:44:29 volumion2 volumio[2597]: Simple mixer control 'EQ enable',0 Sep 01 18:44:29 volumion2 volumio[2597]: Capabilities: pswitch pswitch-joined Sep 01 18:44:29 volumion2 volumio[2597]: Playback channels: Mono Sep 01 18:44:29 volumion2 volumio[2597]: Mono: Playback [on] Sep 01 18:44:29 volumion2 volumio[2597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 01 18:44:29 volumion2 volumio[2597]: info: Reloading queue from file Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::setRepeat false single undefined Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::pushState Sep 01 18:44:29 volumion2 volumio[2597]: info: CorePlayQueue::getTrack 0 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioPushState Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::setRandom false Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::pushState Sep 01 18:44:29 volumion2 volumio[2597]: info: CorePlayQueue::getTrack 0 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioPushState Sep 01 18:44:29 volumion2 volumio[2597]: Unable to parse: Sep 01 18:44:29 volumion2 volumio[2597]: Simple mixer control 'EQ enable',0 Sep 01 18:44:29 volumion2 volumio[2597]: Capabilities: pswitch pswitch-joined Sep 01 18:44:29 volumion2 volumio[2597]: Playback channels: Mono Sep 01 18:44:29 volumion2 volumio[2597]: Mono: Playback [on] Sep 01 18:44:29 volumion2 volumio[2597]: info: VolumeController:: Volume=undefined Mute =false Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::pushState Sep 01 18:44:29 volumion2 volumio[2597]: info: CorePlayQueue::getTrack 0 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioPushState Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::updateTrackBlock Sep 01 18:44:29 volumion2 volumio[2597]: info: CorePlayQueue::getTrackBlock Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 18:44:29 volumion2 volumio[2597]: info: Asound.conf file unchanged, so no further update is needed Sep 01 18:44:29 volumion2 volumio[2597]: info: Output device has changed, restarting MPD Sep 01 18:44:29 volumion2 sudo[2800]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2800]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 volumio[2597]: info: Output device has changed, restarting Shairport Sync Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 sudo[2800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:44:29 volumion2 sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 sudo[2802]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2802]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2800]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 sudo[2802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:44:29 volumion2 sudo[2802]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 volumio[2597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:44:29 volumion2 volumio[2597]: info: ___________ START PLUGINS ___________ Sep 01 18:44:29 volumion2 systemd[1]: Stopping Music Player Daemon... Sep 01 18:44:29 volumion2 volumio[2597]: info: ControllerMpd::onStart: Initializing MPD Sep 01 18:44:29 volumion2 volumio[2597]: info: Creating MPD Configuration file Sep 01 18:44:29 volumion2 sudo[2809]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2809]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:44:29 volumion2 sudo[2809]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 sudo[2811]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2811]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2809]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:44:29 volumion2 sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 volumio[2597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:44:29 volumion2 volumio[2597]: info: [1756745069218] CoreMusicLibrary::Adding element Last_100 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:44:29 volumion2 volumio[2597]: info: [1756745069225] CoreMusicLibrary::Adding element Webradio Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:44:29 volumion2 volumio[2597]: info: Initializing BBC Radios Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: Loading i18n strings for locale pl Sep 01 18:44:29 volumion2 volumio[2597]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: FusionDsp - mixtype--------------------- Hardware Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:44:29 volumion2 volumio[2597]: info: [1756745069306] CoreMusicLibrary::Adding element RADIO 357 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:44:29 volumion2 volumio[2597]: Cannot find translation for source RADIO 357 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:44:29 volumion2 volumio[2597]: info: [1756745069310] CoreMusicLibrary::Adding element Radio Paradise Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:44:29 volumion2 volumio[2597]: Cannot find translation for source RADIO 357 Sep 01 18:44:29 volumion2 volumio[2597]: Cannot find translation for source Radio Paradise Sep 01 18:44:29 volumion2 volumio[2597]: info: Loading i18n strings for locale pl Sep 01 18:44:29 volumion2 volumio[2597]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:44:29 volumion2 volumio[2597]: info: Volumio Calling Home Sep 01 18:44:29 volumion2 systemd[1]: mpd.service: Succeeded. Sep 01 18:44:29 volumion2 systemd[1]: Stopped Music Player Daemon. Sep 01 18:44:29 volumion2 systemd[1]: Starting Music Player Daemon... Sep 01 18:44:29 volumion2 volumio[2597]: info: Preparing to generate the ALSA configuration file Sep 01 18:44:29 volumion2 sudo[2832]: root : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2832]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2832]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:29 volumion2 sudo[2832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 18:44:29 volumion2 sudo[2832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 sudo[2832]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 sudo[2840]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2840]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/hardware.conf /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Sep 01 18:44:29 volumion2 sudo[2840]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 sudo[2840]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 volumio[2597]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 01 18:44:29 volumion2 volumio[2597]: info: Reading ALSA contributions from plugins. Sep 01 18:44:29 volumion2 volumio[2597]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Sep 01 18:44:29 volumion2 volumio[2597]: info: MPD Permissions set Sep 01 18:44:29 volumion2 volumio[2597]: info: MPD Permissions set Sep 01 18:44:29 volumion2 volumio[2597]: Unable to parse: Sep 01 18:44:29 volumion2 volumio[2597]: Simple mixer control 'EQ enable',0 Sep 01 18:44:29 volumion2 volumio[2597]: Capabilities: pswitch pswitch-joined Sep 01 18:44:29 volumion2 volumio[2597]: Playback channels: Mono Sep 01 18:44:29 volumion2 volumio[2597]: Mono: Playback [on] Sep 01 18:44:29 volumion2 volumio[2597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 01 18:44:29 volumion2 volumio[2597]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: Unable to parse: Sep 01 18:44:29 volumion2 volumio[2597]: Simple mixer control 'EQ enable',0 Sep 01 18:44:29 volumion2 volumio[2597]: Capabilities: pswitch pswitch-joined Sep 01 18:44:29 volumion2 volumio[2597]: Playback channels: Mono Sep 01 18:44:29 volumion2 volumio[2597]: Mono: Playback [on] Sep 01 18:44:29 volumion2 volumio[2597]: info: VolumeController:: Volume=undefined Mute =false Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreStateMachine::pushState Sep 01 18:44:29 volumion2 volumio[2597]: info: CorePlayQueue::getTrack 0 Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioPushState Sep 01 18:44:29 volumion2 volumio[2597]: info: Starting Shairport Sync Sep 01 18:44:29 volumion2 volumio[2597]: info: Starting Shairport Sync Sep 01 18:44:29 volumion2 sudo[2856]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2856]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 volumio[2597]: info: Starting Shairport Sync Sep 01 18:44:29 volumion2 sudo[2856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:44:29 volumion2 sudo[2856]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 sudo[2858]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2858]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:44:29 volumion2 sudo[2858]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:44:29 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:44:29 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 sudo[2861]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2861]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::volumioGetState Sep 01 18:44:29 volumion2 volumio[2597]: info: CorePlayQueue::getTrack 0 Sep 01 18:44:29 volumion2 sudo[2856]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 sudo[2861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:44:29 volumion2 sudo[2861]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:44:29 volumion2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 01 18:44:29 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:44:29 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 sudo[2858]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 volumio[2597]: info: ir_controller: LIRC correctly updated. Sep 01 18:44:29 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:44:29 volumion2 volumio[2597]: info: Shairport-Sync Started Sep 01 18:44:29 volumion2 volumio[2597]: Error adding Membership: Error: addMembership EINVAL Sep 01 18:44:29 volumion2 volumio[2597]: info: Asound.conf file unchanged, so no further update is needed Sep 01 18:44:29 volumion2 volumio[2597]: info: Output device has changed, restarting MPD Sep 01 18:44:29 volumion2 sudo[2867]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2867]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 volumio[2597]: info: Output device has changed, restarting Shairport Sync Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 01 18:44:29 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:44:29 volumion2 sudo[2867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:44:29 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 sudo[2867]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 sudo[2861]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 sudo[2867]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 sudo[2869]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2869]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:44:29 volumion2 sudo[2869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 volumio[2597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:44:29 volumion2 volumio[2597]: info: Shairport-Sync Started Sep 01 18:44:29 volumion2 volumio[2597]: info: MPD Permissions set Sep 01 18:44:29 volumion2 volumio[2597]: info: Shairport-Sync Started Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 01 18:44:29 volumion2 systemd[1]: mpd.service: Succeeded. Sep 01 18:44:29 volumion2 systemd[1]: Stopped Music Player Daemon. Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:44:29 volumion2 systemd[1]: Starting Music Player Daemon... Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:44:29 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:29 volumion2 volumio[2597]: info: Starting Shairport Sync Sep 01 18:44:29 volumion2 sudo[2886]: volumio : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2886]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2882]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:29 volumion2 sudo[2882]: root : unable to resolve host volumion2 Sep 01 18:44:29 volumion2 sudo[2882]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:29 volumion2 sudo[2882]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 18:44:29 volumion2 sudo[2886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:44:29 volumion2 sudo[2886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 sudo[2882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:29 volumion2 sudo[2882]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:44:29 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:44:29 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:44:29 volumion2 sudo[2886]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:29 volumion2 volumio[2597]: info: Shairport-Sync Started Sep 01 18:44:30 volumion2 mpd[2890]: Sep 01 18:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 18:44:30 volumion2 systemd[1]: Started Music Player Daemon. Sep 01 18:44:30 volumion2 sudo[2869]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:30 volumion2 sudo[2811]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:30 volumion2 sudo[2802]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:30 volumion2 volumio[2597]: info: Completed starting Core Plugins Sep 01 18:44:30 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:30 volumion2 volumio[2597]: info: ----- MyVolumio plugins startup ---- Sep 01 18:44:30 volumion2 volumio[2597]: info: ------------------------------------------- Sep 01 18:44:30 volumion2 volumio[2597]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 18:44:30 volumion2 volumio[2597]: info: MPD running with PID2890 Sep 01 18:44:30 volumion2 volumio[2597]: ,establishing connection Sep 01 18:44:30 volumion2 volumio[2597]: error: MPD error: The expression evaluated to a falsy value: Sep 01 18:44:30 volumion2 volumio[2597]: assert.ok(self.idling) Sep 01 18:44:30 volumion2 volumio[2597]: error: The expression evaluated to a falsy value: Sep 01 18:44:30 volumion2 volumio[2597]: assert.ok(self.idling) Sep 01 18:44:30 volumion2 volumio[2597]: error: MPD error: The expression evaluated to a falsy value: Sep 01 18:44:30 volumion2 volumio[2597]: assert.ok(self.idling) Sep 01 18:44:30 volumion2 volumio[2597]: error: The expression evaluated to a falsy value: Sep 01 18:44:30 volumion2 volumio[2597]: assert.ok(self.idling) Sep 01 18:44:30 volumion2 volumio[2597]: error: MPD error: The expression evaluated to a falsy value: Sep 01 18:44:30 volumion2 volumio[2597]: assert.ok(self.idling) Sep 01 18:44:30 volumion2 volumio[2597]: error: The expression evaluated to a falsy value: Sep 01 18:44:30 volumion2 volumio[2597]: assert.ok(self.idling) Sep 01 18:44:30 volumion2 volumio[2597]: error: updateQueue error: null Sep 01 18:44:30 volumion2 sudo[2898]: volumio : unable to resolve host volumion2 Sep 01 18:44:30 volumion2 sudo[2898]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:30 volumion2 sudo[2898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Sep 01 18:44:30 volumion2 sudo[2898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:30 volumion2 systemd[1]: Starting lircd(8) initialization helper tool... Sep 01 18:44:30 volumion2 kernel: Registered IR keymap rc-empty Sep 01 18:44:30 volumion2 kernel: input: meson-ir as /devices/platform/ff808040.meson-ir/rc/rc0/input3 Sep 01 18:44:30 volumion2 kernel: rc rc0: meson-ir as /devices/platform/ff808040.meson-ir/rc/rc0 Sep 01 18:44:30 volumion2 lircd-setup[2900]: /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. Sep 01 18:44:30 volumion2 lircd-setup[2900]: parser = configparser.SafeConfigParser() Sep 01 18:44:30 volumion2 kernel: lirc_dev: IR Remote Control driver registered, major 501 Sep 01 18:44:30 volumion2 kernel: meson-ir ff808040.meson-ir: AO_RTI_PIN_MUX : 0x100011 Sep 01 18:44:30 volumion2 kernel: meson-ir ff808040.meson-ir: receiver initialized Sep 01 18:44:30 volumion2 kernel: rc rc0: lirc_dev: driver ir-lirc-codec (meson-ir) registered at minor = 0 Sep 01 18:44:30 volumion2 kernel: IR LIRC bridge handler initialized Sep 01 18:44:30 volumion2 systemd[1]: lircd-setup.service: Succeeded. Sep 01 18:44:30 volumion2 systemd[1]: Started lircd(8) initialization helper tool. Sep 01 18:44:30 volumion2 systemd[1]: Starting Flexible IR remote input/output application support... Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: lircd: Opening log, level: Info Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Version: lircd 0.10.1 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: System info: Linux volumion2 4.9.241+ #8 SMP PREEMPT Sat Jan 21 21:01:33 CET 2023 aarch64 GNU/Linux Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: Initial device: /dev/lirc0 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Info: lircd: Opening log, level: Info Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Notice: Using systemd fd Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Warning: Running as root Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Info: Using remote: odroid. Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: Initial device: /dev/lirc0 Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: [lirc] protocol is enabled Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: driver: default Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: output: /var/run/lirc/lircd Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: nodaemon: 1 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: logfile: syslog Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: immediate-init: 0 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: permission: 666 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: driver-options: Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: listen: 0 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: connect: (null) Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: userelease: 0 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: effective_user: (null) Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: release_suffix: _EVUP Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: allow_simulate: 0 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: repeat_max: 600 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: configfile: /etc/lirc/lircd.conf Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Options: dynamic_codes: (null) Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Current driver: default Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Driver API version: 3 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Driver version: 0.10.0 Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: lircd: Opening log, level: Info Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: Using systemd fd Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Warning: Running as root Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: Using remote: odroid. Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 01 18:44:31 volumion2 systemd[1]: Started Flexible IR remote input/output application support. Sep 01 18:44:31 volumion2 sudo[2898]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:31 volumion2 volumio[2597]: info: ir_controller: systemctl restart lircd.service succeeded. Sep 01 18:44:31 volumion2 sudo[2911]: volumio : unable to resolve host volumion2 Sep 01 18:44:31 volumion2 sudo[2911]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:31 volumion2 sudo[2911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Sep 01 18:44:31 volumion2 sudo[2911]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:31 volumion2 systemd-udevd[2163]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 01 18:44:31 volumion2 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Sep 01 18:44:31 volumion2 sudo[2911]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:31 volumion2 volumio[2597]: info: ir_controller: systemctl restart irexec.service succeeded. Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Notice: accepted new client on /var/run/lirc/lircd Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Notice: accepted new client on /var/run/lirc/lircd Sep 01 18:44:31 volumion2 lircd[2906]: lircd-0.10.1[2906]: Info: [lirc] protocol is enabled Sep 01 18:44:31 volumion2 lircd-0.10.1[2906]: Info: [lirc] protocol is enabled Sep 01 18:44:31 volumion2 kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 01 18:44:31 volumion2 volumio[2597]: info: camilladsp service started and running in background, instance 1 Sep 01 18:44:31 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:44:31 volumion2 volumio[2597]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 01 18:44:31 volumion2 volumio[2597]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 01 18:44:31 volumion2 volumio[2597]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 01 18:44:31 volumion2 volumio[2597]: info: FusionDsp loaded Sep 01 18:44:31 volumion2 volumio[2597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:44:31 volumion2 sudo[2924]: volumio : unable to resolve host volumion2 Sep 01 18:44:31 volumion2 sudo[2924]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:31 volumion2 sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 01 18:44:31 volumion2 sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:31 volumion2 volumio[2597]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 01 18:44:31 volumion2 volumio[2597]: info: Adding Signal Path Element [object Object] Sep 01 18:44:31 volumion2 volumio[2597]: info: Adding fusiondspeq DSP Signal Path Element Sep 01 18:44:31 volumion2 volumio[2597]: info: FusionDsp - ---- installed callbackRead Sep 01 18:44:31 volumion2 volumio[2597]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:44:31 volumion2 volumio[2597]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 01 18:44:31 volumion2 volumio[2597]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 01 18:44:31 volumion2 volumio[2597]: at onErrorNT (internal/child_process.js:472:16) Sep 01 18:44:31 volumion2 volumio[2597]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 01 18:44:31 volumion2 volumio[2597]: at runNextTicks (internal/process/task_queues.js:64:3) Sep 01 18:44:31 volumion2 volumio[2597]: at listOnTimeout (internal/timers.js:526:9) Sep 01 18:44:31 volumion2 volumio[2597]: at processTimers (internal/timers.js:500:7) { Sep 01 18:44:31 volumion2 volumio[2597]: errno: -2, Sep 01 18:44:31 volumion2 volumio[2597]: code: 'ENOENT', Sep 01 18:44:31 volumion2 volumio[2597]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 01 18:44:31 volumion2 volumio[2597]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 01 18:44:31 volumion2 volumio[2597]: spawnargs: [ Sep 01 18:44:31 volumion2 volumio[2597]: '-p', Sep 01 18:44:31 volumion2 volumio[2597]: 9876, Sep 01 18:44:31 volumion2 volumio[2597]: '-o', Sep 01 18:44:31 volumion2 volumio[2597]: '/tmp/camilladsp.log', Sep 01 18:44:31 volumion2 volumio[2597]: '-l', Sep 01 18:44:31 volumion2 volumio[2597]: 'warn', Sep 01 18:44:31 volumion2 volumio[2597]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 01 18:44:31 volumion2 volumio[2597]: ] Sep 01 18:44:31 volumion2 volumio[2597]: } Sep 01 18:44:31 volumion2 volumio[2597]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:44:31 volumion2 systemd[1]: Started FusionDsp Daemon. Sep 01 18:44:31 volumion2 sudo[2924]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:31 volumion2 volumio[2927]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 01 18:44:31 volumion2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 01 18:44:31 volumion2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 01 18:44:31 volumion2 sudo[2936]: volumio : unable to resolve host volumion2 Sep 01 18:44:31 volumion2 sudo[2936]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 01 18:44:31 volumion2 sudo[2936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 18:43 Sep 01 18:44:31 volumion2 sudo[2936]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:31 volumion2 sudo[2936]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:31 volumion2 volumio[2597]: sudo: unable to resolve host volumion2: System error Sep 01 18:44:31 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 18:44:31 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:44:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 18:44:31 volumion2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 18:44:31 volumion2 systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 18:44:31 volumion2 systemd[1]: Started dynamicswap service. Sep 01 18:44:31 volumion2 systemd[1]: dynamicswap.service: Succeeded. Sep 01 18:44:32 volumion2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 01 18:44:32 volumion2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 01 18:44:32 volumion2 systemd[1]: Started dynamicswap service. Sep 01 18:44:32 volumion2 systemd[1]: Stopped Volumio Backend Module. Sep 01 18:44:32 volumion2 systemd[1]: Started Volumio Backend Module. Sep 01 18:44:32 volumion2 systemd[1]: dynamicswap.service: Succeeded. Sep 01 18:44:32 volumion2 dhcpcd[2369]: eth0: leased 192.168.1.237 for 259200 seconds Sep 01 18:44:32 volumion2 dhcpcd[2369]: eth0: adding route to 192.168.1.0/24 Sep 01 18:44:32 volumion2 avahi-daemon[2298]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.237. Sep 01 18:44:32 volumion2 dhcpcd[2369]: eth0: adding default route via 192.168.1.1 Sep 01 18:44:32 volumion2 avahi-daemon[2298]: New relevant interface eth0.IPv4 for mDNS. Sep 01 18:44:32 volumion2 avahi-daemon[2298]: Registering new address record for 192.168.1.237 on eth0.IPv4. Sep 01 18:44:32 volumion2 ntpd[2492]: ntpd exiting on signal 15 (Terminated) Sep 01 18:44:32 volumion2 systemd[1]: Stopping Network Time Service... Sep 01 18:44:32 volumion2 systemd[1]: ntp.service: Succeeded. Sep 01 18:44:32 volumion2 systemd[1]: Stopped Network Time Service. Sep 01 18:44:32 volumion2 systemd[1]: Starting Network Time Service... Sep 01 18:44:32 volumion2 ntpd[2996]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 18:44:32 volumion2 ntpd[2996]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 01 18:44:32 volumion2 systemd[1]: Started Network Time Service. Sep 01 18:44:32 volumion2 ntpd[3002]: proto: precision = 0.750 usec (-20) Sep 01 18:44:32 volumion2 ntpd[3002]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 18:44:32 volumion2 ntpd[3002]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 18:44:32 volumion2 ntpd[3002]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 979 days ago Sep 01 18:44:32 volumion2 ntpd[3002]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 18:44:32 volumion2 ntpd[3002]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 18:44:32 volumion2 ntpd[3002]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 18:44:32 volumion2 ntpd[3002]: Listen normally on 3 eth0 192.168.1.237:123 Sep 01 18:44:32 volumion2 ntpd[3002]: Listening on routing socket on fd #20 for interface updates Sep 01 18:44:32 volumion2 ntpd[3002]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 18:44:32 volumion2 ntpd[3002]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 18:44:32 volumion2 sh[2252]: eth0: leased 192.168.1.237 for 259200 seconds Sep 01 18:44:32 volumion2 sh[2252]: eth0: adding route to 192.168.1.0/24 Sep 01 18:44:32 volumion2 sh[2252]: eth0: adding default route via 192.168.1.1 Sep 01 18:44:32 volumion2 dhcpcd[2286]: eth0: leased 192.168.1.237 for 259200 seconds Sep 01 18:44:32 volumion2 dhcpcd[2286]: eth0: adding route to 192.168.1.0/24 Sep 01 18:44:32 volumion2 dhcpcd[2286]: eth0: adding default route via 192.168.1.1 Sep 01 18:44:32 volumion2 sh[2252]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Sep 01 18:44:32 volumion2 ntpd[3002]: ntpd exiting on signal 15 (Terminated) Sep 01 18:44:32 volumion2 systemd[1]: Stopping Network Time Service... Sep 01 18:44:32 volumion2 systemd[1]: ntp.service: Succeeded. Sep 01 18:44:32 volumion2 systemd[1]: Stopped Network Time Service. Sep 01 18:44:32 volumion2 systemd[1]: Starting Network Time Service... Sep 01 18:44:32 volumion2 ntpd[3037]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 01 18:44:32 volumion2 ntpd[3037]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 01 18:44:32 volumion2 ntpd[3051]: proto: precision = 0.792 usec (-20) Sep 01 18:44:32 volumion2 ntpd[3051]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 01 18:44:32 volumion2 ntpd[3051]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 01 18:44:32 volumion2 ntpd[3051]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 979 days ago Sep 01 18:44:32 volumion2 ntpd[3051]: Listen and drop on 0 v6wildcard [::]:123 Sep 01 18:44:32 volumion2 ntpd[3051]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 01 18:44:32 volumion2 ntpd[3051]: Listen normally on 2 lo 127.0.0.1:123 Sep 01 18:44:32 volumion2 ntpd[3051]: Listen normally on 3 eth0 192.168.1.237:123 Sep 01 18:44:32 volumion2 ntpd[3051]: Listening on routing socket on fd #20 for interface updates Sep 01 18:44:32 volumion2 ntpd[3051]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 18:44:32 volumion2 ntpd[3051]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 01 18:44:32 volumion2 systemd[1]: Started Network Time Service. Sep 01 18:44:32 volumion2 sh[2252]: forked to background, child pid 3053 Sep 01 18:44:32 volumion2 dhcpcd[2286]: forked to background, child pid 3053 Sep 01 18:44:32 volumion2 ifplugd(eth0)[2535]: client: ifup: interface eth0 already configured Sep 01 18:44:32 volumion2 sh[2252]: eth0=eth0 Sep 01 18:44:32 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:44:32 volumion2 volumio[2950]: info: ----- Volumio3 ---- Sep 01 18:44:32 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:44:32 volumion2 volumio[2950]: info: ----- System startup ---- Sep 01 18:44:32 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:44:32 volumion2 ifplugd(eth0)[2535]: Program executed successfully. Sep 01 18:44:33 volumion2 volumio[2950]: info: MYVOLUMIO Environment detected Sep 01 18:44:33 volumion2 volumio[2950]: info: Plugin folders cleanup Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning into folder /volumio/app/plugins/ Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category audio_interface Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category miscellanea Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category music_service Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category plugins.json Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category system_controller Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category user_interface Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning into folder /data/plugins/ Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category audio_interface Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category music_service Sep 01 18:44:33 volumion2 volumio[2950]: info: Scanning category system_hardware Sep 01 18:44:33 volumion2 volumio[2950]: info: Plugin folders cleanup completed Sep 01 18:44:33 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:44:33 volumion2 volumio[2950]: info: ----- Core plugins startup ---- Sep 01 18:44:33 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 18:44:33 volumion2 volumio[2950]: info: Adding plugin upnp to MyMusic Plugins Sep 01 18:44:33 volumion2 volumio[2950]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 18:44:33 volumion2 volumio[2950]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugins from folder /data/plugins/ Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "system"... Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "appearance"... Sep 01 18:44:33 volumion2 volumio-time-update[2317]: volumio-time-update-util: Fetching time from Volumio... Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "network"... Sep 01 18:44:33 volumion2 volumio[2950]: info: Refreshing Cached IP Addresses Sep 01 18:44:33 volumion2 sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:44:33 volumion2 volumio-time-update[2317]: volumio-time-update-util: Setting system time to: 2025-09-01 18:45:25 Sep 01 18:44:33 volumion2 sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:33 volumion2 sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:44:33 volumion2 sudo[3091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "services"... Sep 01 18:44:33 volumion2 sudo[3089]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "alsa_controller"... Sep 01 18:44:33 volumion2 sudo[3098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 18:44:33 volumion2 sudo[3098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:33 volumion2 sudo[3093]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-01 18:45:25 Sep 01 18:44:33 volumion2 sudo[3093]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:33 volumion2 sudo[3091]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:33 volumion2 sudo[3098]: pam_unix(sudo:session): session closed for user root Sep 01 18:44:33 volumion2 dbus-daemon[2295]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=3110 comm="timedatectl set-time 2025-09-01 18:45:25 " label="kernel") Sep 01 18:44:33 volumion2 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 01 18:44:33 volumion2 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Sep 01 18:44:33 volumion2 systemd[1]: Stopped FusionDsp Daemon. Sep 01 18:44:33 volumion2 systemd[1]: Started FusionDsp Daemon. Sep 01 18:44:33 volumion2 systemd[1]: Starting Time & Date Service... Sep 01 18:44:33 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "wizard"... Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "networkfs"... Sep 01 18:44:33 volumion2 volumio[3123]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 01 18:44:33 volumion2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 01 18:44:33 volumion2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 01 18:44:33 volumion2 sudo[3126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=maslako,password=Pantera27!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.1.251/Muzyka /mnt/NAS/DELL Sep 01 18:44:33 volumion2 sudo[3126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:33 volumion2 systemd[1]: Started Time & Date Service. Sep 01 18:44:33 volumion2 volumio[2950]: info: Starting Udev Watcher for removable devices Sep 01 18:44:33 volumion2 volumio[2950]: info: Ignoring mount for partition: boot Sep 01 18:44:33 volumion2 volumio[2950]: info: Ignoring mount for partition: volumio Sep 01 18:44:33 volumion2 volumio[2950]: info: Ignoring mount for partition: volumio_data Sep 01 18:44:33 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "volumio_command_line_client"... Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "upnp"... Sep 01 18:44:33 volumion2 volumio[2950]: info: [1756745073813] Starting Upmpd Daemon Sep 01 18:44:33 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "my_music"... Sep 01 18:44:33 volumion2 volumio[2950]: info: Loading plugin "mpd"... Sep 01 18:44:33 volumion2 sudo[3128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.247:/Pobrane/Muzyka /mnt/NAS/OMV Sep 01 18:44:33 volumion2 sudo[3128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:44:33 volumion2 dbus-daemon[2295]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 01 18:44:33 volumion2 sudo[3131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=maslako,password=Pantera27!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.1.251/Muzyka /mnt/NAS/Muzyka Sep 01 18:44:33 volumion2 sudo[3131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:25 volumion2 systemd-timedated[3124]: Changed local time to Mon Sep 1 18:45:25 2025 Sep 01 18:45:25 volumion2 sudo[3093]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:25 volumion2 volumio-time-update[2317]: volumio-time-update-util: System time updated successfully. Sep 01 18:45:25 volumion2 systemd[1]: Started Volumio Time Update Utility. Sep 01 18:45:25 volumion2 sudo[3128]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:25 volumion2 nmbd[2494]: [2025/09/01 18:45:25.078202, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 18:45:25 volumion2 nmbd[2494]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 01 18:45:25 volumion2 systemd[1]: Started Samba NMB Daemon. Sep 01 18:45:25 volumion2 systemd[1]: Starting Samba Winbind Daemon... Sep 01 18:45:25 volumion2 sudo[3126]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:25 volumion2 sudo[3131]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:25 volumion2 volumio[2950]: info: Plugin upnp_browser is not enabled Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "alarm-clock"... Sep 01 18:45:25 volumion2 ntpd[3051]: Soliciting pool server 194.50.19.117 Sep 01 18:45:25 volumion2 winbindd[3141]: [2025/09/01 18:45:25.223127, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 01 18:45:25 volumion2 winbindd[3141]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "airplay_emulation"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Starting Shairport Sync Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "last_100"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "webradio"... Sep 01 18:45:25 volumion2 winbindd[3141]: [2025/09/01 18:45:25.242512, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 18:45:25 volumion2 systemd[1]: Started Samba Winbind Daemon. Sep 01 18:45:25 volumion2 winbindd[3141]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 01 18:45:25 volumion2 systemd[1]: Starting Samba SMB Daemon... Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "i2s_dacs"... Sep 01 18:45:25 volumion2 volumio[2950]: info: I2S DAC not set, start Auto-detection Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "volumiodiscovery"... Sep 01 18:45:25 volumion2 volumio[2950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 18:45:25 volumion2 volumio[2950]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:45:25 volumion2 volumio[2950]: *** WARNING *** For more information see Sep 01 18:45:25 volumion2 node[2950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 18:45:25 volumion2 volumio[2950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 18:45:25 volumion2 volumio[2950]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:45:25 volumion2 volumio[2950]: *** WARNING *** For more information see Sep 01 18:45:25 volumion2 node[2950]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:45:25 volumion2 node[2950]: *** WARNING *** For more information see Sep 01 18:45:25 volumion2 node[2950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 18:45:25 volumion2 node[2950]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:45:25 volumion2 node[2950]: *** WARNING *** For more information see Sep 01 18:45:25 volumion2 volumio[2950]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 18:45:25 volumion2 volumio[2950]: info: Discovery: Started advertising with name: VolumioN2+ Sep 01 18:45:25 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "outputs"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "albumart"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Plugin example_plugin is not enabled Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "inputs"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "updater_comm"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Plugin mpdemulation is not enabled Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "rest_api"... Sep 01 18:45:25 volumion2 winbindd[3141]: [2025/09/01 18:45:25.470957, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Sep 01 18:45:25 volumion2 winbindd[3141]: res_names->count = 3, expected 4 Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "websocket"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Starting Socket.io Server version 2.3.0 Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "fusiondsp"... Sep 01 18:45:25 volumion2 smbd[3159]: [2025/09/01 18:45:25.499607, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 01 18:45:25 volumion2 systemd[1]: Started Samba SMB Daemon. Sep 01 18:45:25 volumion2 smbd[3159]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 01 18:45:25 volumion2 systemd[1]: Reached target Multi-User System. Sep 01 18:45:25 volumion2 systemd[1]: Reached target Graphical Interface. Sep 01 18:45:25 volumion2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 01 18:45:25 volumion2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 01 18:45:25 volumion2 systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 01 18:45:25 volumion2 systemd[1]: Startup finished in 10.383s (kernel) + 12.750s (userspace) = 23.134s. Sep 01 18:45:25 volumion2 volumio[2950]: Forking 5 albumart workers Sep 01 18:45:25 volumion2 volumio[2950]: info: Applying required configuration parameters for plugin fusiondsp Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "rad357pl"... Sep 01 18:45:25 volumion2 volumio[2950]: info: Applying required configuration parameters for plugin rad357pl Sep 01 18:45:25 volumion2 volumio[2950]: info: [1756745125893] [rad357pl] API delay: 5 Sep 01 18:45:25 volumion2 volumio[2950]: info: Loading plugin "radio_paradise"... Sep 01 18:45:25 volumion2 volumio[2950]: Starting albumart workers Sep 01 18:45:25 volumion2 volumio[2950]: Starting albumart workers Sep 01 18:45:26 volumion2 ntpd[3051]: Soliciting pool server 178.254.3.244 Sep 01 18:45:26 volumion2 ntpd[3051]: Soliciting pool server 185.252.140.126 Sep 01 18:45:26 volumion2 volumio[2950]: Starting albumart workers Sep 01 18:45:26 volumion2 volumio[2950]: Starting albumart workers Sep 01 18:45:26 volumion2 volumio[2950]: Starting albumart workers Sep 01 18:45:26 volumion2 volumio[2950]: info: Applying required configuration parameters for plugin radio_paradise Sep 01 18:45:26 volumion2 volumio[2950]: info: [1756745126133] [RadioParadise] API delay: 5 Sep 01 18:45:26 volumion2 volumio[2950]: info: Loading plugin "ir_controller"... Sep 01 18:45:26 volumion2 volumio[2950]: info: Applying required configuration parameters for plugin ir_controller Sep 01 18:45:26 volumion2 volumio[2950]: info: Loading i18n strings for locale pl Sep 01 18:45:26 volumion2 volumio[2950]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 01 18:45:26 volumion2 volumio[2950]: Updating browse sources language Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::initPlayerControls Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: Express server listening on port 3000 Sep 01 18:45:26 volumion2 volumio[2950]: [Metrics] WebUI: 2s 605.80ms Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::resetVolumioState Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::getcurrentVolume Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 01 18:45:26 volumion2 volumio[2950]: info: Completed loading Core Plugins Sep 01 18:45:26 volumion2 volumio[2950]: info: Preparing to generate the ALSA configuration file Sep 01 18:45:26 volumion2 volumio[2950]: info: Volumio Network Manager: Network status updated: 1 Sep 01 18:45:26 volumion2 volumio[2950]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 01 18:45:26 volumion2 volumio[2950]: wlan0 Interface doesn't support scanning. Sep 01 18:45:26 volumion2 volumio[2950]: info: Cannot use regular scanning, forcing with ap-force Sep 01 18:45:26 volumion2 sudo[3257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 01 18:45:26 volumion2 sudo[3257]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:26 volumion2 sudo[3257]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:26 volumion2 volumio[2950]: command failed: No such device (-19) Sep 01 18:45:26 volumion2 volumio[2950]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 01 18:45:26 volumion2 volumio[2950]: command failed: No such device (-19) Sep 01 18:45:26 volumion2 volumio[2950]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 01 18:45:26 volumion2 volumio[2950]: info: Reading ALSA contributions from plugins. Sep 01 18:45:26 volumion2 volumio[2950]: Unable to parse: Sep 01 18:45:26 volumion2 volumio[2950]: Simple mixer control 'EQ enable',0 Sep 01 18:45:26 volumion2 volumio[2950]: Capabilities: pswitch pswitch-joined Sep 01 18:45:26 volumion2 volumio[2950]: Playback channels: Mono Sep 01 18:45:26 volumion2 volumio[2950]: Mono: Playback [on] Sep 01 18:45:26 volumion2 volumio[2950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 01 18:45:26 volumion2 volumio[2950]: info: Reloading queue from file Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::setRepeat false single undefined Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::pushState Sep 01 18:45:26 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioPushState Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::setRandom false Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::pushState Sep 01 18:45:26 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioPushState Sep 01 18:45:26 volumion2 volumio[2950]: Unable to parse: Sep 01 18:45:26 volumion2 volumio[2950]: Simple mixer control 'EQ enable',0 Sep 01 18:45:26 volumion2 volumio[2950]: Capabilities: pswitch pswitch-joined Sep 01 18:45:26 volumion2 volumio[2950]: Playback channels: Mono Sep 01 18:45:26 volumion2 volumio[2950]: Mono: Playback [on] Sep 01 18:45:26 volumion2 volumio[2950]: info: VolumeController:: Volume=undefined Mute =false Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::pushState Sep 01 18:45:26 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioPushState Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreStateMachine::updateTrackBlock Sep 01 18:45:26 volumion2 volumio[2950]: info: CorePlayQueue::getTrackBlock Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 18:45:26 volumion2 volumio[2950]: Unable to parse: Sep 01 18:45:26 volumion2 volumio[2950]: Simple mixer control 'EQ enable',0 Sep 01 18:45:26 volumion2 volumio[2950]: Capabilities: pswitch pswitch-joined Sep 01 18:45:26 volumion2 volumio[2950]: Playback channels: Mono Sep 01 18:45:26 volumion2 volumio[2950]: Mono: Playback [on] Sep 01 18:45:26 volumion2 volumio[2950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 01 18:45:26 volumion2 volumio[2950]: info: Discovery: adding 1e121759-eeb4-41b8-bda6-bf1ffcafd9dc Sep 01 18:45:26 volumion2 volumio[2950]: info: Discovery: Found device VolumioN2+ Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioGetState Sep 01 18:45:26 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:26 volumion2 volumio[2950]: info: Asound.conf file unchanged, so no further update is needed Sep 01 18:45:26 volumion2 volumio[2950]: info: Output device has changed, restarting MPD Sep 01 18:45:26 volumion2 sudo[3271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:45:26 volumion2 volumio[2950]: info: Output device has changed, restarting Shairport Sync Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:26 volumion2 sudo[3271]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:26 volumion2 sudo[3271]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:26 volumion2 sudo[3273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:45:26 volumion2 sudo[3273]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:26 volumion2 systemd[1]: Stopping Music Player Daemon... Sep 01 18:45:26 volumion2 volumio[2950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:45:26 volumion2 volumio[2950]: info: ___________ START PLUGINS ___________ Sep 01 18:45:26 volumion2 volumio[2950]: info: ControllerMpd::onStart: Initializing MPD Sep 01 18:45:26 volumion2 volumio[2950]: info: Creating MPD Configuration file Sep 01 18:45:26 volumion2 systemd[1]: mpd.service: Succeeded. Sep 01 18:45:26 volumion2 systemd[1]: Stopped Music Player Daemon. Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:26 volumion2 systemd[1]: Starting Music Player Daemon... Sep 01 18:45:26 volumion2 sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:45:26 volumion2 sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:26 volumion2 sudo[3281]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:26 volumion2 sudo[3282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:45:26 volumion2 sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:26 volumion2 volumio[2950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:26 volumion2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 01 18:45:26 volumion2 systemd[1]: mpd.service: Succeeded. Sep 01 18:45:26 volumion2 systemd[1]: Stopped Music Player Daemon. Sep 01 18:45:26 volumion2 systemd[1]: Starting Music Player Daemon... Sep 01 18:45:26 volumion2 volumio[2950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:45:26 volumion2 volumio[2950]: info: [1756745126909] CoreMusicLibrary::Adding element Last_100 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:45:26 volumion2 volumio[2950]: info: [1756745126911] CoreMusicLibrary::Adding element Webradio Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:45:26 volumion2 volumio[2950]: info: Initializing BBC Radios Sep 01 18:45:26 volumion2 sudo[3292]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 18:45:26 volumion2 sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:26 volumion2 sudo[3292]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: Loading i18n strings for locale pl Sep 01 18:45:26 volumion2 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 01 18:45:26 volumion2 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Sep 01 18:45:26 volumion2 volumio[2950]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 01 18:45:26 volumion2 systemd[1]: Stopped FusionDsp Daemon. Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 01 18:45:26 volumion2 systemd[1]: Started FusionDsp Daemon. Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:26 volumion2 volumio[2950]: info: FusionDsp - mixtype--------------------- Hardware Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:45:26 volumion2 volumio[2950]: info: [1756745126983] CoreMusicLibrary::Adding element RADIO 357 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:45:26 volumion2 volumio[2950]: Cannot find translation for source RADIO 357 Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:45:26 volumion2 volumio[2950]: info: [1756745126987] CoreMusicLibrary::Adding element Radio Paradise Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:45:26 volumion2 volumio[2950]: Cannot find translation for source RADIO 357 Sep 01 18:45:26 volumion2 volumio[2950]: Cannot find translation for source Radio Paradise Sep 01 18:45:26 volumion2 volumio[2950]: info: Loading i18n strings for locale pl Sep 01 18:45:26 volumion2 volumio[2950]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 01 18:45:26 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:45:26 volumion2 volumio[2950]: info: Volumio Calling Home Sep 01 18:45:27 volumion2 ntpd[3051]: Soliciting pool server 94.130.35.4 Sep 01 18:45:27 volumion2 ntpd[3051]: Soliciting pool server 141.144.241.16 Sep 01 18:45:27 volumion2 volumio[3305]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 01 18:45:27 volumion2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 01 18:45:27 volumion2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 01 18:45:27 volumion2 volumio[2950]: info: Preparing to generate the ALSA configuration file Sep 01 18:45:27 volumion2 sudo[3312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/hardware.conf /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Sep 01 18:45:27 volumion2 sudo[3312]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 sudo[3312]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 ntpd[3051]: Soliciting pool server 162.159.200.1 Sep 01 18:45:27 volumion2 volumio[2950]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 01 18:45:27 volumion2 volumio[2950]: info: Reading ALSA contributions from plugins. Sep 01 18:45:27 volumion2 volumio[2950]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Sep 01 18:45:27 volumion2 volumio[2950]: info: MPD Permissions set Sep 01 18:45:27 volumion2 volumio[2950]: info: MPD Permissions set Sep 01 18:45:27 volumion2 volumio[2950]: Unable to parse: Sep 01 18:45:27 volumion2 volumio[2950]: Simple mixer control 'EQ enable',0 Sep 01 18:45:27 volumion2 volumio[2950]: Capabilities: pswitch pswitch-joined Sep 01 18:45:27 volumion2 volumio[2950]: Playback channels: Mono Sep 01 18:45:27 volumion2 volumio[2950]: Mono: Playback [on] Sep 01 18:45:27 volumion2 volumio[2950]: info: VolumeController:: Volume=undefined Mute =false Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreStateMachine::pushState Sep 01 18:45:27 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::volumioPushState Sep 01 18:45:27 volumion2 volumio[2950]: info: Volumio called home Sep 01 18:45:27 volumion2 volumio[2950]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: Starting Shairport Sync Sep 01 18:45:27 volumion2 volumio[2950]: info: Starting Shairport Sync Sep 01 18:45:27 volumion2 volumio[2950]: info: Starting Shairport Sync Sep 01 18:45:27 volumion2 sudo[3327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:45:27 volumion2 sudo[3327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 sudo[3329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:45:27 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:45:27 volumion2 sudo[3329]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:45:27 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 sudo[3327]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::volumioGetState Sep 01 18:45:27 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:27 volumion2 sudo[3332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:45:27 volumion2 sudo[3332]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 volumio[2950]: info: ir_controller: LIRC correctly updated. Sep 01 18:45:27 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:45:27 volumion2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 01 18:45:27 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:45:27 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 volumio[2950]: info: Shairport-Sync Started Sep 01 18:45:27 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 sudo[3329]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 volumio[2950]: Error adding Membership: Error: addMembership EINVAL Sep 01 18:45:27 volumion2 volumio[2950]: info: Asound.conf file unchanged, so no further update is needed Sep 01 18:45:27 volumion2 volumio[2950]: info: Output device has changed, restarting MPD Sep 01 18:45:27 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:45:27 volumion2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 01 18:45:27 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:45:27 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 volumio[2950]: info: Output device has changed, restarting Shairport Sync Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:27 volumion2 sudo[3339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:45:27 volumion2 sudo[3339]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 volumio[2950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 18:45:27 volumion2 volumio[2950]: info: Shairport-Sync Started Sep 01 18:45:27 volumion2 volumio[2950]: info: MPD Permissions set Sep 01 18:45:27 volumion2 volumio[2950]: info: Shairport-Sync Started Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 sudo[3339]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:45:27 volumion2 sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 sudo[3332]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:45:27 volumion2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 01 18:45:27 volumion2 systemd[1]: mpd.service: Succeeded. Sep 01 18:45:27 volumion2 systemd[1]: Stopped Music Player Daemon. Sep 01 18:45:27 volumion2 systemd[1]: Starting Music Player Daemon... Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:27 volumion2 volumio[2950]: info: Starting Shairport Sync Sep 01 18:45:27 volumion2 sudo[3357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 18:45:27 volumion2 sudo[3357]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 18:45:27 volumion2 systemd[1]: shairport-sync.service: Succeeded. Sep 01 18:45:27 volumion2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 01 18:45:27 volumion2 sudo[3357]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 sudo[3352]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 18:45:27 volumion2 sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 sudo[3352]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 sudo[3362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:45:27 volumion2 sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 volumio[2950]: info: Shairport-Sync Started Sep 01 18:45:27 volumion2 sudo[3362]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 sudo[3365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:45:27 volumion2 sudo[3365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:27 volumion2 sudo[3365]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:27 volumion2 volumio[2950]: verbose: New Socket.io Connection to 192.168.1.237 from 192.168.1.251 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::volumioGetState Sep 01 18:45:27 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 18:45:27 volumion2 volumio[2950]: info: Listing playlists Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 01 18:45:27 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:45:27 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:45:27] [connect] Successful connection Sep 01 18:45:27 volumion2 volumio-remote-updater[2292]: [2025-09-01 18:45:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756745127 101 Sep 01 18:45:27 volumion2 volumio[2950]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Sep 01 18:45:28 volumion2 ntpd[3051]: Soliciting pool server 176.9.42.91 Sep 01 18:45:28 volumion2 ntpd[3051]: Soliciting pool server 31.209.85.243 Sep 01 18:45:28 volumion2 ntpd[3051]: Soliciting pool server 85.215.93.134 Sep 01 18:45:28 volumion2 mpd[3369]: Sep 01 18:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 18:45:28 volumion2 ntpd[3051]: Soliciting pool server 194.59.205.229 Sep 01 18:45:28 volumion2 systemd[1]: Started Music Player Daemon. Sep 01 18:45:28 volumion2 sudo[3341]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:28 volumion2 sudo[3282]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:28 volumion2 sudo[3273]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:28 volumion2 volumio[2950]: info: Completed starting Core Plugins Sep 01 18:45:28 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:45:28 volumion2 volumio[2950]: info: ----- MyVolumio plugins startup ---- Sep 01 18:45:28 volumion2 volumio[2950]: info: ------------------------------------------- Sep 01 18:45:28 volumion2 volumio[2950]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 18:45:28 volumion2 volumio[2950]: error: MPD error: The expression evaluated to a falsy value: Sep 01 18:45:28 volumion2 volumio[2950]: assert.ok(self.idling) Sep 01 18:45:28 volumion2 volumio[2950]: error: The expression evaluated to a falsy value: Sep 01 18:45:28 volumion2 volumio[2950]: assert.ok(self.idling) Sep 01 18:45:28 volumion2 volumio[2950]: error: MPD error: The expression evaluated to a falsy value: Sep 01 18:45:28 volumion2 volumio[2950]: assert.ok(self.idling) Sep 01 18:45:28 volumion2 volumio[2950]: error: The expression evaluated to a falsy value: Sep 01 18:45:28 volumion2 volumio[2950]: assert.ok(self.idling) Sep 01 18:45:28 volumion2 volumio[2950]: error: updateQueue error: null Sep 01 18:45:28 volumion2 volumio[2950]: info: MPD running with PID3369 Sep 01 18:45:28 volumion2 volumio[2950]: ,establishing connection Sep 01 18:45:28 volumion2 volumio[2950]: error: updateQueue error: null Sep 01 18:45:28 volumion2 sudo[3379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Sep 01 18:45:28 volumion2 sudo[3379]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:28 volumion2 lircd[2906]: lircd-0.10.1[2906]: Notice: caught signal Sep 01 18:45:28 volumion2 lircd-0.10.1[2906]: Notice: caught signal Sep 01 18:45:28 volumion2 systemd[1]: Stopping Flexible IR remote input/output application support... Sep 01 18:45:28 volumion2 systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Sep 01 18:45:28 volumion2 systemd[1]: lircd.service: Succeeded. Sep 01 18:45:28 volumion2 systemd[1]: Stopped Flexible IR remote input/output application support. Sep 01 18:45:28 volumion2 systemd[1]: irexec.service: Succeeded. Sep 01 18:45:28 volumion2 systemd[1]: Starting lircd(8) initialization helper tool... Sep 01 18:45:28 volumion2 lircd-setup[3381]: /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. Sep 01 18:45:28 volumion2 lircd-setup[3381]: parser = configparser.SafeConfigParser() Sep 01 18:45:28 volumion2 systemd[1]: lircd-setup.service: Succeeded. Sep 01 18:45:28 volumion2 systemd[1]: Started lircd(8) initialization helper tool. Sep 01 18:45:28 volumion2 systemd[1]: Starting Flexible IR remote input/output application support... Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: lircd: Opening log, level: Info Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Version: lircd 0.10.1 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: System info: Linux volumion2 4.9.241+ #8 SMP PREEMPT Sat Jan 21 21:01:33 CET 2023 aarch64 GNU/Linux Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: Initial device: /dev/lirc0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: [lirc] protocol is enabled Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Info: lircd: Opening log, level: Info Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Notice: Using systemd fd Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Warning: Running as root Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Info: Using remote: odroid. Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 01 18:45:28 volumion2 systemd[1]: Started Flexible IR remote input/output application support. Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: Initial device: /dev/lirc0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: [lirc] protocol is enabled Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: driver: default Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: output: /var/run/lirc/lircd Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: nodaemon: 1 Sep 01 18:45:28 volumion2 volumio[2950]: info: ir_controller: systemctl restart lircd.service succeeded. Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: logfile: syslog Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: immediate-init: 0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: permission: 666 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: driver-options: Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: listen: 0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: connect: (null) Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: userelease: 0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: effective_user: (null) Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: release_suffix: _EVUP Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: allow_simulate: 0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: repeat_max: 600 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: configfile: /etc/lirc/lircd.conf Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Options: dynamic_codes: (null) Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Current driver: default Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Driver API version: 3 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Driver version: 0.10.0 Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: lircd: Opening log, level: Info Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: Using systemd fd Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Warning: Running as root Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: Using remote: odroid. Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 01 18:45:28 volumion2 sudo[3379]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:28 volumion2 sudo[3388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Sep 01 18:45:28 volumion2 sudo[3388]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:28 volumion2 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Notice: accepted new client on /var/run/lirc/lircd Sep 01 18:45:28 volumion2 lircd[3384]: lircd-0.10.1[3384]: Info: [lirc] protocol is enabled Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Notice: accepted new client on /var/run/lirc/lircd Sep 01 18:45:28 volumion2 lircd-0.10.1[3384]: Info: [lirc] protocol is enabled Sep 01 18:45:28 volumion2 sudo[3388]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:28 volumion2 volumio[2950]: info: ir_controller: systemctl restart irexec.service succeeded. Sep 01 18:45:28 volumion2 volumio[2950]: info: VolumeController::SetAlsaVolume0 Sep 01 18:45:28 volumion2 volumio[2950]: info: CoreStateMachine::pushState Sep 01 18:45:28 volumion2 volumio[2950]: info: CorePlayQueue::getTrack 0 Sep 01 18:45:28 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:45:28 volumion2 volumio[2950]: info: CoreCommandRouter::volumioPushState Sep 01 18:45:29 volumion2 volumio[2950]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 01 18:45:29 volumion2 volumio[2950]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 01 18:45:29 volumion2 volumio[2950]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 01 18:45:29 volumion2 ntpd[3051]: Soliciting pool server 79.133.44.143 Sep 01 18:45:29 volumion2 ntpd[3051]: Soliciting pool server 158.180.28.150 Sep 01 18:45:29 volumion2 ntpd[3051]: Soliciting pool server 3.121.254.221 Sep 01 18:45:29 volumion2 volumio[2950]: info: camilladsp service started and running in background, instance 1 Sep 01 18:45:29 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:45:29 volumion2 volumio[2950]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 01 18:45:29 volumion2 volumio[2950]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 01 18:45:29 volumion2 volumio[2950]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 01 18:45:29 volumion2 volumio[2950]: info: FusionDsp loaded Sep 01 18:45:29 volumion2 volumio[2950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:45:29 volumion2 volumio[2950]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 01 18:45:29 volumion2 volumio[2950]: info: Adding Signal Path Element [object Object] Sep 01 18:45:29 volumion2 volumio[2950]: info: Adding fusiondspeq DSP Signal Path Element Sep 01 18:45:29 volumion2 volumio[2950]: info: FusionDsp - ---- installed callbackRead Sep 01 18:45:29 volumion2 volumio[2950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:45:29 volumion2 sudo[3402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 01 18:45:29 volumion2 sudo[3402]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:45:29 volumion2 volumio[2950]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 01 18:45:29 volumion2 volumio[2950]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 01 18:45:29 volumion2 volumio[2950]: at onErrorNT (internal/child_process.js:472:16) Sep 01 18:45:29 volumion2 volumio[2950]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Sep 01 18:45:29 volumion2 volumio[2950]: errno: -2, Sep 01 18:45:29 volumion2 volumio[2950]: code: 'ENOENT', Sep 01 18:45:29 volumion2 volumio[2950]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 01 18:45:29 volumion2 volumio[2950]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 01 18:45:29 volumion2 volumio[2950]: spawnargs: [ Sep 01 18:45:29 volumion2 volumio[2950]: '-p', Sep 01 18:45:29 volumion2 volumio[2950]: 9876, Sep 01 18:45:29 volumion2 volumio[2950]: '-o', Sep 01 18:45:29 volumion2 volumio[2950]: '/tmp/camilladsp.log', Sep 01 18:45:29 volumion2 volumio[2950]: '-l', Sep 01 18:45:29 volumion2 volumio[2950]: 'warn', Sep 01 18:45:29 volumion2 volumio[2950]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 01 18:45:29 volumion2 volumio[2950]: ] Sep 01 18:45:29 volumion2 volumio[2950]: } Sep 01 18:45:29 volumion2 volumio[2950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:45:29 volumion2 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 01 18:45:29 volumion2 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Sep 01 18:45:29 volumion2 systemd[1]: Stopped FusionDsp Daemon. Sep 01 18:45:29 volumion2 systemd[1]: Started FusionDsp Daemon. Sep 01 18:45:29 volumion2 sudo[3402]: pam_unix(sudo:session): session closed for user root Sep 01 18:45:29 volumion2 volumio[3406]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 01 18:45:29 volumion2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 01 18:45:29 volumion2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 01 18:45:29 volumion2 sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 18:44 Sep 01 18:45:29 volumion2 sudo[3414]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 29 Jul 2025 01:38:55 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="odroidn2" VOLUMIO_DEVICENAME="Odroid-N2" VOLUMIO_HASH="7ccd4ec723650356901df6b4b03e93ae"