-- Logs begin at Tue 2024-10-01 10:46:45 UTC, end at Tue 2024-10-01 10:47:48 UTC. -- Oct 01 10:46:45 volumio systemd-journal[2896]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 146.8M free of 974.5M available → current limit 30.0M). Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys cpuset Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys cpu Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys cpuacct Oct 01 10:46:45 volumio kernel: Linux version 3.14.29-amlogics905x-gf29b82a-dirty (root@user-VirtualBox) (gcc version 6.4.1 20171012 (Linaro GCC 6.4-2017.11) ) #169 SMP PREEMPT Sun May 20 09:10:33 MSK 2018 Oct 01 10:46:45 volumio kernel: CPU: AArch64 Processor [410fd034] revision 4 Oct 01 10:46:45 volumio kernel: no prop version_code Oct 01 10:46:45 volumio kernel: fdt Reserved memory table: Oct 01 10:46:45 volumio kernel: linux,di_cma: 0x000000007cc00000 - 0x000000007f000000 (36 MiB) Oct 01 10:46:45 volumio kernel: cma: Reserved 36 MiB at 7cc00000, total cma pages:9216 Oct 01 10:46:45 volumio kernel: linux,ion-dev: 0x000000007cbff000 - 0x000000007cc00000 (0 MiB) Oct 01 10:46:45 volumio kernel: linux,vdin1_cma: 0x000000007b800000 - 0x000000007c800000 (16 MiB) Oct 01 10:46:45 volumio kernel: cma: Reserved 16 MiB at 7b800000, total cma pages:13312 Oct 01 10:46:45 volumio kernel: linux,ppmgr: 0x000000007cbff000 - 0x000000007cbff000 (0 MiB) Oct 01 10:46:45 volumio kernel: linux,codec_mm_cma: 0x0000000067800000 - 0x0000000073800000 (192 MiB) Oct 01 10:46:45 volumio kernel: cma: Reserved 192 MiB at 67800000, total cma pages:62464 Oct 01 10:46:45 volumio kernel: linux,picdec: 0x000000007cbff000 - 0x000000007cbff000 (0 MiB) Oct 01 10:46:45 volumio kernel: Reserved memory: incorrect alignment of CMA region Oct 01 10:46:45 volumio kernel: linux,codec_mm_reserved: 0x000000007cb00000 - 0x000000007cb00000 (0 MiB) Oct 01 10:46:45 volumio kernel: fdt Reserved memory total: 264 MiB Oct 01 10:46:45 volumio kernel: cma: Reserved 8 MiB at 7b000000, total cma pages:64512 Oct 01 10:46:45 volumio kernel: On node 0 totalpages: 518912 Oct 01 10:46:45 volumio kernel: Normal zone: 11171 pages used for memmap Oct 01 10:46:45 volumio kernel: Normal zone: 0 pages reserved Oct 01 10:46:45 volumio kernel: Normal zone: 518912 pages, LIFO batch:31 Oct 01 10:46:45 volumio kernel: psci: probing function IDs from device-tree Oct 01 10:46:45 volumio kernel: PERCPU: Embedded 13 pages/cpu @ffffffc07cb9a000 s20608 r8192 d24448 u53248 Oct 01 10:46:45 volumio kernel: pcpu-alloc: s20608 r8192 d24448 u53248 alloc=13*4096 Oct 01 10:46:45 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 01 10:46:45 volumio kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 507741 Oct 01 10:46:45 volumio kernel: Kernel command line: imgpart=LABEL=volumio imgfile=/volumio_current.sqsh bootpart=LABEL=BOOT datapart=LABEL=volumio_data rootwait rw console=ttyS0,115200n8 console=tty0 no_console_suspend consoleblank=0 fsck.fix=yes fsck.repair=yes net.ifnames=0 mac=${mac} bootdelay=5 Oct 01 10:46:45 volumio kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Oct 01 10:46:45 volumio kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) Oct 01 10:46:45 volumio kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) Oct 01 10:46:45 volumio kernel: Memory: 1740612K/2075648K available (10994K kernel code, 981K rwdata, 4028K rodata, 1236K init, 4557K bss, 335036K reserved) Oct 01 10:46:45 volumio kernel: Virtual kernel memory layout: vmalloc : 0xffffff8000000000 - 0xffffff8040000000 ( 1024 MB) vmemmap : 0xffffff8040010000 - 0xffffff8042bb8000 ( 43 MB) modules : 0xffffffbffc000000 - 0xffffffc000000000 ( 64 MB) memory : 0xffffffc000000000 - 0xffffffc07f000000 ( 2032 MB) .init : 0xffffffc001f2d000 - 0xffffffc002062080 ( 1237 kB) .text : 0xffffffc001080000 - 0xffffffc001f2caf4 ( 15027 kB) .data : 0xffffffc002063000 - 0xffffffc002158670 ( 982 kB) Oct 01 10:46:45 volumio kernel: PM: Registered nosave memory: [mem 0x01080000-0x01f2bfff] Oct 01 10:46:45 volumio kernel: SLUB: HWalign=64, Order=0-1, MinObjects=0, CPUs=4, Nodes=1 Oct 01 10:46:45 volumio kernel: arch_multi_cluster:0 Oct 01 10:46:45 volumio kernel: Preemptible hierarchical RCU implementation. Oct 01 10:46:45 volumio kernel: RCU debugfs-based tracing is enabled. Oct 01 10:46:45 volumio kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Oct 01 10:46:45 volumio kernel: RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 01 10:46:45 volumio kernel: NR_IRQS:64 nr_irqs:64 0 Oct 01 10:46:45 volumio kernel: Architected cp15 timer(s) running at 24.00MHz (phys). Oct 01 10:46:45 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 2863311519744ns Oct 01 10:46:45 volumio kernel: meson_bc_timer: mclk->mux_reg =ffffff800000c990,mclk->reg =ffffff800000e994 Oct 01 10:46:45 volumio kernel: Console: colour dummy device 80x25 Oct 01 10:46:45 volumio kernel: console [tty0] enabled Oct 01 10:46:45 volumio kernel: allocated 8388608 bytes of page_cgroup Oct 01 10:46:45 volumio kernel: please try 'cgroup_disable=memory' option if you don't want memory cgroups Oct 01 10:46:45 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000) Oct 01 10:46:45 volumio kernel: pid_max: default: 32768 minimum: 301 Oct 01 10:46:45 volumio kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) Oct 01 10:46:45 volumio kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys memory Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys devices Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys freezer Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys blkio Oct 01 10:46:45 volumio kernel: Initializing cgroup subsys perf_event Oct 01 10:46:45 volumio kernel: ftrace: allocating 36702 entries in 144 pages Oct 01 10:46:45 volumio kernel: /cpus/cpu@0: Missing clock-frequency property Oct 01 10:46:45 volumio kernel: /cpus/cpu@1: Missing clock-frequency property Oct 01 10:46:45 volumio kernel: /cpus/cpu@2: Missing clock-frequency property Oct 01 10:46:45 volumio kernel: /cpus/cpu@3: Missing clock-frequency property Oct 01 10:46:45 volumio kernel: hw perfevents: enabled with arm/armv8-pmuv3 PMU driver, 7 counters available Oct 01 10:46:45 volumio kernel: Meson chip version = RevD (21:D - A4:0) Oct 01 10:46:45 volumio kernel: CPU1: Booted secondary processor Oct 01 10:46:45 volumio kernel: CPU2: Booted secondary processor Oct 01 10:46:45 volumio kernel: CPU3: Booted secondary processor Oct 01 10:46:45 volumio kernel: Brought up 4 CPUs Oct 01 10:46:45 volumio kernel: SMP: Total of 4 processors activated. Oct 01 10:46:45 volumio kernel: devtmpfs: initialized Oct 01 10:46:45 volumio kernel: sched: registering cpufreq notifiers for scale-invariant loads Oct 01 10:46:45 volumio kernel: instabooting: 0 Oct 01 10:46:45 volumio kernel: cma: cma_init_reserved_areas, use_cma_first:1 Oct 01 10:46:45 volumio kernel: xor: measuring software checksum speed Oct 01 10:46:45 volumio kernel: 8regs : 2292.000 MB/sec Oct 01 10:46:45 volumio kernel: 8regs_prefetch: 2041.200 MB/sec Oct 01 10:46:45 volumio kernel: 32regs : 2811.200 MB/sec Oct 01 10:46:45 volumio kernel: 32regs_prefetch: 2373.200 MB/sec Oct 01 10:46:45 volumio kernel: xor: using function: 32regs (2811.200 MB/sec) Oct 01 10:46:45 volumio kernel: pinctrl core: initialized pinctrl subsystem Oct 01 10:46:45 volumio kernel: regulator-dummy: no parameters Oct 01 10:46:45 volumio kernel: NET: Registered protocol family 16 Oct 01 10:46:45 volumio kernel: cpuidle: using governor menu Oct 01 10:46:45 volumio kernel: aml_vdac_init: module init Oct 01 10:46:45 volumio kernel: register canvas platform driver Oct 01 10:46:45 volumio kernel: register rdma platform driver Oct 01 10:46:45 volumio kernel: vdso: 2 pages (1 code, 1 data) at base ffffffc00206c000 Oct 01 10:46:45 volumio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 01 10:46:45 volumio kernel: DMA: preallocated 256 KiB pool for atomic allocations Oct 01 10:46:45 volumio kernel: software IO TLB [mem 0x76400000-0x76800000] (4MB) mapped at [ffffffc076400000-ffffffc0767fffff] Oct 01 10:46:45 volumio kernel: Serial: AMBA PL011 UART driver Oct 01 10:46:45 volumio kernel: aml_i2c version: 20140813 Oct 01 10:46:45 volumio kernel: i2c-core: driver [pmu4] using legacy resume method Oct 01 10:46:45 volumio kernel: codec_mm:codec_mm_module_init Oct 01 10:46:45 volumio kernel: aml_watch_point_probe, in Oct 01 10:46:45 volumio kernel: gxbb_mpll_clk: register mpll_clk_out0 success done Oct 01 10:46:45 volumio kernel: gxbb_mpll_clk: register mpll_clk_out1 success done Oct 01 10:46:45 volumio kernel: gxbb_mpll_clk: register mpll_clk_out2 success done Oct 01 10:46:45 volumio kernel: clk_sys: register PLL sys_pll success done Oct 01 10:46:45 volumio kernel: gxl_clk: [ xtal ] ->clockrate: 24000000Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ 32Khz ] ->clockrate: 32000Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ clk81 ] ->clockrate: 166666666Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ fixed_pll ] ->clockrate: 2000000000Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ fclk_div2 ] ->clockrate: 1000000000Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ fclk_div3 ] ->clockrate: 666666666Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ fclk_div4 ] ->clockrate: 500000000Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ fclk_div5 ] ->clockrate: 400000000Hz Oct 01 10:46:45 volumio kernel: gxl_clk: [ fclk_div7 ] ->clockrate: 285714285Hz Oct 01 10:46:45 volumio kernel: gxl_clk: clock initialization complete Oct 01 10:46:45 volumio kernel: clkmsr: Gxl msr_clk_reg0=ffffff800005c75c,msr_clk_reg2=ffffff800005e764 Oct 01 10:46:45 volumio kernel: aml_iomap: amlogic iomap probe done Oct 01 10:46:45 volumio kernel: pinmux-gxl c1109880.pinmux: Init pinux probe! Oct 01 10:46:45 volumio kernel: pinmux-gxl c1109880.pinmux: Probed amlogic pinctrl driver Oct 01 10:46:45 volumio kernel: genirq: Setting trigger mode 8 for irq 241 failed (gic_set_type+0x0/0xb8) Oct 01 10:46:45 volumio kernel: genirq: Setting trigger mode 8 for irq 242 failed (gic_set_type+0x0/0xb8) Oct 01 10:46:45 volumio kernel: genirq: Setting trigger mode 8 for irq 241 failed (gic_set_type+0x0/0xb8) Oct 01 10:46:45 volumio kernel: genirq: Setting trigger mode 8 for irq 242 failed (gic_set_type+0x0/0xb8) Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - buf.opp[0].freq_hz = 100000000 Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - buf.opp[1].freq_hz = 250000000 Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - buf.opp[2].freq_hz = 500000000 Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - buf.opp[3].freq_hz = 667000000 Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - buf.opp[4].freq_hz = 1000000000 Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - buf.opp[5].freq_hz = 1200000000 Oct 01 10:46:45 volumio kernel: dvfs [scpi_dvfs_get_opps] - new count 6, max_freq 1200000000 Oct 01 10:46:45 volumio kernel: codec_mm has 2 memory regions Oct 01 10:46:45 volumio kernel: codec_mm codec_mm.21: assigned reserved memory node linux,codec_mm_cma ok Oct 01 10:46:45 volumio kernel: codec_mm codec_mm.21: assigned reserved memory node linux,codec_mm_reserved ok Oct 01 10:46:45 volumio kernel: codec_mm has 2 memory regions Oct 01 10:46:45 volumio kernel: codec_mm codec_mm.21: assigned reserved memory node linux,codec_mm_cma ok Oct 01 10:46:45 volumio kernel: codec_mm codec_mm.21: assigned reserved memory node linux,codec_mm_reserved ok Oct 01 10:46:45 volumio kernel: vpu: driver version: v03 Oct 01 10:46:45 volumio kernel: vpu: load vpu_clk: 666667000Hz(7) Oct 01 10:46:45 volumio kernel: vpu: vpu_probe OK Oct 01 10:46:45 volumio kernel: tv_vout: tvout_probe Oct 01 10:46:45 volumio kernel: tv_vout: chrdev devno 266338304 for disp Oct 01 10:46:45 volumio kernel: vout_notify: vout_register_server Oct 01 10:46:45 volumio kernel: tv_vout: register tv module server ok Oct 01 10:46:45 volumio kernel: tv_vout: create cdev tv Oct 01 10:46:45 volumio kernel: tv_vout: tvout_probe OK Oct 01 10:46:45 volumio kernel: canvas_probe reg=00000000c8838000,size=400 Oct 01 10:46:45 volumio kernel: canvas maped reg_base =ffffff800007e000 Oct 01 10:46:45 volumio kernel: rdma_probe Oct 01 10:46:45 volumio kernel: bio: create slab at 0 Oct 01 10:46:45 volumio kernel: raid6: int64x1 491 MB/s Oct 01 10:46:45 volumio kernel: raid6: int64x2 717 MB/s Oct 01 10:46:45 volumio kernel: raid6: int64x4 1039 MB/s Oct 01 10:46:45 volumio kernel: raid6: int64x8 1199 MB/s Oct 01 10:46:45 volumio kernel: raid6: neonx1 767 MB/s Oct 01 10:46:45 volumio kernel: raid6: neonx2 1242 MB/s Oct 01 10:46:45 volumio kernel: raid6: neonx4 1596 MB/s Oct 01 10:46:45 volumio kernel: raid6: neonx8 1692 MB/s Oct 01 10:46:45 volumio kernel: raid6: using algorithm neonx8 (1692 MB/s) Oct 01 10:46:45 volumio kernel: raid6: using intx1 recovery algorithm Oct 01 10:46:45 volumio kernel: SCSI subsystem initialized Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver usbfs Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver hub Oct 01 10:46:45 volumio kernel: usbcore: registered new device driver usb Oct 01 10:46:45 volumio kernel: i2c-gpio i2c_gpio.41: using pins 172 (SDA) and 173 (SCL) Oct 01 10:46:45 volumio kernel: media: Linux media interface: v0.10 Oct 01 10:46:45 volumio kernel: Linux video capture interface: v2.00 Oct 01 10:46:45 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Oct 01 10:46:45 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 01 10:46:45 volumio kernel: PTP clock support registered Oct 01 10:46:45 volumio kernel: hdmitx: system: amhdmitx_init Oct 01 10:46:45 volumio kernel: hdmitx: system: Ver: 2014May6 Oct 01 10:46:45 volumio kernel: hdmitx: system: amhdmitx_probe Oct 01 10:46:45 volumio kernel: hdmitx: system: not find match init-data Oct 01 10:46:45 volumio kernel: hdmitx hpd irq = 89 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xc0800000 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xc1104400 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xc8100000 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xc8834000 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xc883a000 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xc883c000 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xd0100000 Oct 01 10:46:45 volumio kernel: hdmitx20: Mapped PHY: 0xda83a000 Oct 01 10:46:45 volumio kernel: avmute set to 1 Oct 01 10:46:45 volumio kernel: vout_serve: no matched vout_init mode Oct 01 10:46:45 volumio kernel: vinfo: error: invalid vinfo. current vmode is not supported Oct 01 10:46:45 volumio kernel: vout_serve: create_vout_fops OK Oct 01 10:46:45 volumio kernel: vout_serve: create vout attribute OK Oct 01 10:46:45 volumio kernel: vout_serve: meson_vout_probe OK Oct 01 10:46:45 volumio kernel: logo: logo_init Oct 01 10:46:45 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Oct 01 10:46:45 volumio kernel: Switched to clocksource arch_sys_counter Oct 01 10:46:45 volumio kernel: FS-Cache: Loaded Oct 01 10:46:45 volumio kernel: CacheFiles: Loaded Oct 01 10:46:45 volumio kernel: NET: Registered protocol family 2 Oct 01 10:46:45 volumio kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes) Oct 01 10:46:45 volumio kernel: TCP bind hash table entries: 16384 (order: 6, 262144 bytes) Oct 01 10:46:45 volumio kernel: TCP: Hash tables configured (established 16384 bind 16384) Oct 01 10:46:45 volumio kernel: TCP: reno registered Oct 01 10:46:45 volumio kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes) Oct 01 10:46:45 volumio kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) Oct 01 10:46:45 volumio kernel: NET: Registered protocol family 1 Oct 01 10:46:45 volumio kernel: RPC: Registered named UNIX socket transport module. Oct 01 10:46:45 volumio kernel: RPC: Registered udp transport module. Oct 01 10:46:45 volumio kernel: RPC: Registered tcp transport module. Oct 01 10:46:45 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] no power_on_pin2 Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] set pwm as 32k output Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] dhd_static_buf setup Oct 01 10:46:45 volumio kernel: Wifi: bcmdhd_init_wlan_mem: bcmdhd_init_wlan_mem: WIFI MEM Allocated Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] interrupt_pin=241 Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] irq_num=100, irq_trigger_type=1 Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] power_on_pin=240 Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_dev_probe] clock_32k_pin=0 Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_setup_dt] wifi_setup_dt Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_setup_dt] interrupt_pin(241) Oct 01 10:46:45 volumio kernel: aml_wifi wifi.36: [wifi_setup_dt] power_on_pin(240) Oct 01 10:46:45 volumio kernel: Unpacking initramfs... Oct 01 10:46:45 volumio kernel: Freeing initrd memory: 4484K (ffffffc073a3c000 - ffffffc073e9d000) Oct 01 10:46:45 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Oct 01 10:46:45 volumio kernel: audit: initializing netlink subsys (disabled) Oct 01 10:46:45 volumio kernel: audit: type=2000 audit(2.410:1): initialized Oct 01 10:46:45 volumio kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages Oct 01 10:46:45 volumio kernel: VFS: Disk quotas dquot_6.5.2 Oct 01 10:46:45 volumio kernel: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 01 10:46:45 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 01 10:46:45 volumio kernel: NFS: Registering the id_resolver key type Oct 01 10:46:45 volumio kernel: Key type id_resolver registered Oct 01 10:46:45 volumio kernel: Key type id_legacy registered Oct 01 10:46:45 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 01 10:46:45 volumio kernel: jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc. Oct 01 10:46:45 volumio kernel: ROMFS MTD (C) 2007 Red Hat, Inc. Oct 01 10:46:45 volumio kernel: fuse init (API version 7.22) Oct 01 10:46:45 volumio kernel: msgmni has been set to 3912 Oct 01 10:46:45 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) Oct 01 10:46:45 volumio kernel: io scheduler noop registered (default) Oct 01 10:46:45 volumio kernel: io scheduler deadline registered Oct 01 10:46:45 volumio kernel: io scheduler cfq registered Oct 01 10:46:45 volumio kernel: meson-rng c8834000.rng: hwrng registered Oct 01 10:46:45 volumio kernel: [drm] Initialized drm 1.1.0 20060810 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: max pp is 3 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: set min pp to default 1 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: min pp is 1 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: set min clk default to 0 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: min clk is 0 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: hiu io source 0xffffff8000404440 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: hiu io source 0xffffff80001de000 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: num of pp used most of time 3 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: clock dvfs table size is 6 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: max clk set 4 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: max clk is 4 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: turbo clk set to 5 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: turbo clk is 5 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: default clk set to 2 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: default clk is 2 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: ====================0==================== clk_freq= 125000000, clk_parent=fclk_div4, voltage=1150, keep_count=5, threshod=<30 250>, clk_sample=125 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: ====================1==================== clk_freq= 285000000, clk_parent=fclk_div7, voltage=1150, keep_count=5, threshod=<100 250>, clk_sample=285 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: ====================2==================== clk_freq= 400000000, clk_parent=fclk_div5, voltage=1150, keep_count=3, threshod=<168 250>, clk_sample=400 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: ====================3==================== clk_freq= 500000000, clk_parent=fclk_div4, voltage=1150, keep_count=2, threshod=<190 250>, clk_sample=500 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: ====================4==================== clk_freq= 666000000, clk_parent=fclk_div3, voltage=1150, keep_count=1, threshod=<177 250>, clk_sample=666 Oct 01 10:46:45 volumio kernel: mali-utgard d00c0000.mali: ====================5==================== clk_freq= 666000000, clk_parent=fclk_div3, voltage=1150, keep_count=1, threshod=<177 250>, clk_sample=666 Oct 01 10:46:45 volumio kernel: gpu cooling register okay with err=0 Oct 01 10:46:45 volumio kernel: gpu core cooling register okay with err=0 Oct 01 10:46:45 volumio kernel: Mali: Mali device driver loaded Oct 01 10:46:45 volumio kernel: UMP: UMP device driver -f29b82a loaded Oct 01 10:46:45 volumio kernel: loop: module loaded Oct 01 10:46:45 volumio kernel: mtdoops: mtd device (mtddev=name/number) must be supplied Oct 01 10:46:45 volumio kernel: tun: Universal TUN/TAP device driver, 1.6 Oct 01 10:46:45 volumio kernel: tun: (C) 1999-2004 Max Krasnyansky Oct 01 10:46:45 volumio kernel: libphy: stmmac: probed Oct 01 10:46:45 volumio kernel: eth0: PHY ID 01814400 at 8 IRQ 41 (stmmac-0:08) active Oct 01 10:46:45 volumio kernel: PPP generic driver version 2.4.2 Oct 01 10:46:45 volumio kernel: NET: Registered protocol family 24 Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver cdc_ether Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver cdc_ncm Oct 01 10:46:45 volumio kernel: platform c9000000.dwc3: Driver dwc3 requests probe deferral Oct 01 10:46:45 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Oct 01 10:46:45 volumio kernel: ehci-h20ahb: H20AHB-EHCI Host Controller driver Oct 01 10:46:45 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver cdc_acm Oct 01 10:46:45 volumio kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver usb-storage Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver usbserial Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver usbserial_generic Oct 01 10:46:45 volumio kernel: usbserial: USB Serial support registered for generic Oct 01 10:46:45 volumio kernel: amlogic-new-usb2 d0078000.usb2phy: USB2 phy probe:phy_mem:0xd0078000, iomap phy_base:0xffffff8000594000 Oct 01 10:46:45 volumio kernel: amlogic-new-usb3 d0078080.usb3phy: This phy has no usb port Oct 01 10:46:45 volumio kernel: amlogic-new-usb3 d0078080.usb3phy: USB3 phy probe:phy_mem:0xd0078080, iomap phy_base:0xffffff8000596080 Oct 01 10:46:45 volumio kernel: mousedev: PS/2 mouse device common for all mice Oct 01 10:46:45 volumio kernel: i2c /dev entries driver Oct 01 10:46:45 volumio kernel: CPUidle arm64: cpuidle init start!! Oct 01 10:46:45 volumio kernel: CPUidle arm64: cpuidle init done! Oct 01 10:46:45 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 01 10:46:45 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Oct 01 10:46:45 volumio kernel: usbcore: registered new interface driver usbhid Oct 01 10:46:45 volumio kernel: usbhid: USB HID core driver Oct 01 10:46:45 volumio kernel: meson_uart c81004c0.serial: ==uart0 reg addr = ffffff80005984c0 Oct 01 10:46:45 volumio kernel: c81004c0.serial: ttyS0 at MMIO 0xc81004c0 (irq = 225, base_baud = 1500000) is a meson_uart Oct 01 10:46:45 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 0 to 115200 Oct 01 10:46:45 volumio kernel: console [ttyS0] enabled Oct 01 10:46:45 volumio kernel: meson_uart c11084c0.serial: ==uart1 reg addr = ffffff800059a4c0 Oct 01 10:46:45 volumio kernel: c11084c0.serial: ttyS1 at MMIO 0xc11084c0 (irq = 58, base_baud = 1500000) is a meson_uart Oct 01 10:46:45 volumio kernel: dwc_otg: usb0: type: 2 speed: 0, config: 0, dma: 0, id: 0, phy: d0078000, ctrl: 0 Oct 01 10:46:45 volumio kernel: dwc_otg_driver_probe host only, not probe usb_otg!!! Oct 01 10:46:45 volumio kernel: hdmitx: system: hdmitx_hdcp_init Oct 01 10:46:45 volumio kernel: aml_vrtc rtc.35: rtc core: registered aml_vrtc as rtc0 Oct 01 10:46:45 volumio kernel: BLASTER Driver Init Oct 01 10:46:45 volumio kernel: secmon has 1 memory regions Oct 01 10:46:45 volumio kernel: secmon: share in base: 0xffffff80005d6000, share out base: 0xffffff80005d8000 Oct 01 10:46:45 volumio kernel: secmon secmon.4: assigned reserved memory node linux,secmon ok Oct 01 10:46:45 volumio kernel: secmon: probe done Oct 01 10:46:45 volumio kernel: storage: storage in base: 0xffffff8001600000 Oct 01 10:46:45 volumio kernel: storage: storage out base: 0xffffff8001680000 Oct 01 10:46:45 volumio kernel: storage: storage block base: 0xffffff8001700000 Oct 01 10:46:45 volumio kernel: storage: probe done! Oct 01 10:46:45 volumio kernel: Error: Driver 'ICPlus IP175C' is already registered, aborting... Oct 01 10:46:45 volumio kernel: libphy: ICPlus IP175C: Error -16 in registering driver Oct 01 10:46:45 volumio kernel: Error: Driver 'AMLOGIC internal phy' is already registered, aborting... Oct 01 10:46:45 volumio kernel: libphy: AMLOGIC internal phy: Error -16 in registering driver Oct 01 10:46:45 volumio kernel: Error: Driver 'RTL8211F Gigabit Ethernet' is already registered, aborting... Oct 01 10:46:45 volumio kernel: libphy: RTL8211F Gigabit Ethernet: Error -16 in registering driver Oct 01 10:46:45 volumio kernel: aml_sd_emmc_probe: line 3594 Oct 01 10:46:45 volumio kernel: mmc driver version: 1.07, 2015-01-21: fix a bug in tuning which caused eMMC data CRC error Oct 01 10:46:45 volumio kernel: aml_sd_emmc_reg_init 1149 Oct 01 10:46:45 volumio kernel: get property: ocr_avail, value:0x00200080 Oct 01 10:46:45 volumio kernel: get property: f_min, value:300000 Oct 01 10:46:45 volumio kernel: get property: f_max, value:50000000 Oct 01 10:46:45 volumio kernel: get property: max_req_size, value:0x00020000 Oct 01 10:46:45 volumio kernel: get property: pinname, str:emmc Oct 01 10:46:45 volumio kernel: get property: card_type, value:1 Oct 01 10:46:45 volumio kernel: get property: gpio_dat3, str: Oct 01 10:46:45 volumio kernel: get property: hw_reset, str: Oct 01 10:46:45 volumio kernel: emmc:pdata->caps = c0000d47 Oct 01 10:46:45 volumio kernel: emmc:pdata->caps2 = 0 Oct 01 10:46:45 volumio kernel: emmc:pdata->pm_caps = 0 Oct 01 10:46:45 volumio kernel: storage 1, boot 1 Oct 01 10:46:45 volumio kernel: get_storage_dev return 1 Oct 01 10:46:45 volumio kernel: [aml_sd_emmc_probe] aml_sd_emmc_probe() success! Oct 01 10:46:45 volumio kernel: aml_sd_emmc_probe: line 3594 Oct 01 10:46:45 volumio kernel: aml_sd_emmc_reg_init 1149 Oct 01 10:46:45 volumio kernel: get property: ocr_avail, value:0x00200080 Oct 01 10:46:45 volumio kernel: get property: f_min, value:400000 Oct 01 10:46:45 volumio kernel: get property: f_max, value:100000000 Oct 01 10:46:45 volumio kernel: get property: max_req_size, value:0x00020000 Oct 01 10:46:45 volumio kernel: get property: irq_in, value:3 Oct 01 10:46:45 volumio kernel: get property: irq_out, value:5 Oct 01 10:46:45 volumio kernel: get property: gpio_cd, str: Oct 01 10:46:45 volumio kernel: get property: pinname, str:sd Oct 01 10:46:45 volumio kernel: get property: jtag_pin, str: Oct 01 10:46:45 volumio kernel: get property: card_type, value:5 Oct 01 10:46:45 volumio kernel: get property: gpio_dat3, str: Oct 01 10:46:45 volumio kernel: sd:pdata->caps = 7 Oct 01 10:46:45 volumio kernel: sd:pdata->caps2 = 0 Oct 01 10:46:45 volumio kernel: sd:pdata->pm_caps = 0 Oct 01 10:46:45 volumio kernel: [aml_is_card_insert] card IN Oct 01 10:46:45 volumio kernel: emmc: BKOPS_EN bit is not set Oct 01 10:46:45 volumio kernel: [aml_sd_emmc_probe] aml_sd_emmc_probe() success! Oct 01 10:46:45 volumio kernel: aml_sd_emmc_probe: line 3594 Oct 01 10:46:45 volumio kernel: emmc: try set sd/emmc to DDR mode Oct 01 10:46:45 volumio kernel: emmc: new DDR MMC card at address 0001 Oct 01 10:46:45 volumio kernel: emmc: clock 50000000, 8-bit-bus-width Oct 01 10:46:45 volumio kernel: mmcblk0: emmc:0001 SCY 14.5 GiB Oct 01 10:46:45 volumio kernel: mmcblk0boot0: emmc:0001 SCY partition 1 4.00 MiB Oct 01 10:46:45 volumio kernel: mmcblk0boot1: emmc:0001 SCY partition 2 4.00 MiB Oct 01 10:46:45 volumio kernel: aml_sd_emmc_reg_init 1149 Oct 01 10:46:45 volumio kernel: get property: ocr_avail, value:0x00200080 Oct 01 10:46:45 volumio kernel: get property: f_min, value:400000 Oct 01 10:46:45 volumio kernel: get property: f_max, value:200000000 Oct 01 10:46:45 volumio kernel: get property: max_req_size, value:0x00020000 Oct 01 10:46:45 volumio kernel: get property: pinname, str:sdio Oct 01 10:46:45 volumio kernel: get property: vol_switch_delay, value:10 Oct 01 10:46:45 volumio kernel: get property: card_type, value:3 Oct 01 10:46:45 volumio kernel: sdio:pdata->caps = 7810f Oct 01 10:46:45 volumio kernel: sdio:pdata->caps2 = 0 Oct 01 10:46:45 volumio kernel: sdio:pdata->pm_caps = 1 Oct 01 10:46:45 volumio kernel: [aml_sd_emmc_probe] aml_sd_emmc_probe() success! Oct 01 10:46:45 volumio kernel: ion_dev has 1 memory regions Oct 01 10:46:45 volumio kernel: normal card in Oct 01 10:46:45 volumio kernel: [aml_is_card_insert] card IN Oct 01 10:46:45 volumio kernel: mmcblk0rpmb: emmc:0001 SCY partition 3 4.00 MiB Oct 01 10:46:45 volumio kernel: fb: osd_init_module Oct 01 10:46:45 volumio kernel: fb: viu vsync irq: 35 Oct 01 10:46:45 volumio kernel: meson-fb has 1 memory regions Oct 01 10:46:45 volumio kernel: meson-fb meson-fb.18: assigned reserved memory node linux,meson-fb ok Oct 01 10:46:45 volumio kernel: tv_vout: tv_set_current_vmode[919]fps_target_mode=10 Oct 01 10:46:45 volumio kernel: tv_vout: mode is 10,sync_duration_den=1,sync_duration_num=60 Oct 01 10:46:45 volumio kernel: tv_vout: TV mode 720p60hz selected. Oct 01 10:46:45 volumio kernel: vdac_enable: on:0,module_sel:8 Oct 01 10:46:45 volumio kernel: vdac_enable: on:0,module_sel:8 Oct 01 10:46:45 volumio kernel: tv_vout: new mode =720p60hz set ok Oct 01 10:46:45 volumio kernel: vout_serve: vinfo mode is: 720p60hz Oct 01 10:46:45 volumio kernel: osd_rdma: osd_rdma_init: rdma_table p=0x7b045000,op=0x7b045000 , v=0xffffff80005f6000 Oct 01 10:46:45 volumio kernel: rdma_register, rdma_table_addr ffffff80005f8000 rdma_table_addr_phy 7b04c000 reg_buf ffffffc076193000 Oct 01 10:46:45 volumio kernel: rdma_register success, handle 1 table_size 4096 Oct 01 10:46:45 volumio kernel: osd_rdma: osd_rdma_init:osd reset rdma handle = 1. Oct 01 10:46:45 volumio kernel: rdma_register, rdma_table_addr ffffff80005fa000 rdma_table_addr_phy 7b04d000 reg_buf ffffffc076192000 Oct 01 10:46:45 volumio kernel: rdma_register success, handle 2 table_size 4096 Oct 01 10:46:45 volumio kernel: osd_rdma: osd_rdma_init:osd rdma handle = 2. Oct 01 10:46:45 volumio kernel: fb: osd_probe vinfo:ffffffc0020f2730 Oct 01 10:46:45 volumio kernel: fb: init fbdev bpp is:32 Oct 01 10:46:45 volumio kernel: fb: Frame buffer memory assigned at Oct 01 10:46:45 volumio kernel: fb: 0, phy: 0x000000007f000000, vir:0xffffff8001900000, size=11264K Oct 01 10:46:45 volumio kernel: fb: ---------------clear fb0 memory ffffff8001900000 Oct 01 10:46:45 volumio kernel: fb: osd[0] canvas.idx =0x40 Oct 01 10:46:45 volumio kernel: fb: osd[0] canvas.addr=0x7f000000 Oct 01 10:46:45 volumio kernel: fb: osd[0] canvas.width=5120 Oct 01 10:46:45 volumio kernel: fb: osd[0] canvas.height=2160 Oct 01 10:46:45 volumio kernel: fb: osd[0] frame.width=1280 Oct 01 10:46:45 volumio kernel: fb: osd[0] frame.height=720 Oct 01 10:46:45 volumio kernel: mmcblk0: unknown partition table Oct 01 10:46:45 volumio kernel: [mmc_read_partition_tbl] mmc read partition OK! Oct 01 10:46:45 volumio kernel: add_emmc_partition Oct 01 10:46:45 volumio kernel: [mmcblk0p01] bootloader offset 0x000000000000, size 0x000000400000 Oct 01 10:46:45 volumio kernel: [mmcblk0p02] reserved offset 0x000002400000, size 0x000004000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p03] cache offset 0x000006c00000, size 0x000020000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p04] env offset 0x000027400000, size 0x000000800000 Oct 01 10:46:45 volumio kernel: [mmcblk0p05] logo offset 0x000028400000, size 0x000002000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p06] recovery offset 0x00002ac00000, size 0x000002000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p07] rsv offset 0x00002d400000, size 0x000000800000 Oct 01 10:46:45 volumio kernel: [mmcblk0p08] tee offset 0x00002e400000, size 0x000000800000 Oct 01 10:46:45 volumio kernel: [mmcblk0p09] crypt offset 0x00002f400000, size 0x000002000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p10] misc offset 0x000031c00000, size 0x000002000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p11] boot offset 0x000034400000, size 0x000002000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p12] system offset 0x000036c00000, size 0x000080000000 Oct 01 10:46:45 volumio kernel: [mmcblk0p13] data offset 0x0000b7400000, size 0x0002ec400000 Oct 01 10:46:45 volumio kernel: card key: card_blk_probe. Oct 01 10:46:45 volumio kernel: emmc_key_init:527 emmc key lba_start:0x12020,lba_end:0x12220 Oct 01 10:46:45 volumio kernel: emmc key: emmc_key_init:552 ok. Oct 01 10:46:45 volumio kernel: amlmmc_dtb_init: register dtb chardev Oct 01 10:46:45 volumio kernel: calc 19668582, store 19668582 Oct 01 10:46:45 volumio kernel: calc 19668582, store 19668582 Oct 01 10:46:45 volumio kernel: total valid 2 Oct 01 10:46:45 volumio kernel: amlmmc_dtb_init: register dtb chardev OK Oct 01 10:46:45 volumio kernel: Exit aml_emmc_partition_ops OK. Oct 01 10:46:45 volumio kernel: mmcblk0boot1: unknown partition table Oct 01 10:46:45 volumio kernel: mmcblk0boot0: unknown partition table Oct 01 10:46:45 volumio kernel: clear_emmc_wait_flag Oct 01 10:46:45 volumio kernel: Console: switching to colour frame buffer device 160x45 Oct 01 10:46:45 volumio kernel: sd: new high speed SDHC card at address aaaa Oct 01 10:46:45 volumio kernel: sd: clock 50000000, 4-bit-bus-width Oct 01 10:46:45 volumio kernel: mmcblk1: sd:aaaa SC32G 29.7 GiB Oct 01 10:46:45 volumio kernel: mmcblk1: p1 p2 p3 Oct 01 10:46:45 volumio kernel: fb: set osd0 reverse as NONE Oct 01 10:46:45 volumio kernel: fb: osd probe OK Oct 01 10:46:45 volumio kernel: ge2d: ge2d_init_module Oct 01 10:46:45 volumio kernel: ge2d: ge2d_dev major:238 Oct 01 10:46:45 volumio kernel: ge2d: ge2d clock is 400 MHZ Oct 01 10:46:45 volumio kernel: ge2d: ge2d: pdev=ffffffc0768e7800, irq=182, rstc=0xffffffc073805c00, clk=ffffffc0001bcd00 Oct 01 10:46:45 volumio kernel: ge2d: mapped phy: 0xd0160000 Oct 01 10:46:45 volumio kernel: ge2d: ge2d start monitor Oct 01 10:46:45 volumio kernel: ge2d: ge2d workqueue monitor start Oct 01 10:46:45 volumio kernel: codec:Amlogic A/V streaming port init Oct 01 10:46:45 volumio kernel: codec:get gate demux control ok ffffffc07603b500 Oct 01 10:46:45 volumio kernel: codec:get gate parser_top control ok ffffffc07603b700 Oct 01 10:46:45 volumio kernel: codec:get gate vpu_intr control ok ffffffc07603b840 Oct 01 10:46:45 volumio kernel: codec:get gate vdec control ok ffffffc07603b740 Oct 01 10:46:45 volumio kernel: codec:[tsync_pcr_init]init success. Oct 01 10:46:45 volumio kernel: codec:amvideocap_probe,amvideocap Oct 01 10:46:45 volumio kernel: codec:use cma buf. Oct 01 10:46:45 volumio kernel: amvenc_avc_probe -- reserved memory config fail. Oct 01 10:46:45 volumio kernel: amvenc_avc - cma memory pool size: 40 MB Oct 01 10:46:45 volumio kernel: encode_wq_init. Oct 01 10:46:45 volumio kernel: encode start monitor. Oct 01 10:46:45 volumio kernel: encode workqueue monitor start. Oct 01 10:46:45 volumio kernel: jpegenc module init Oct 01 10:46:45 volumio kernel: codec:picdec_driver_probe called. Oct 01 10:46:45 volumio kernel: picdec has 1 memory regions Oct 01 10:46:45 volumio kernel: codec:picdec_driver_probe done. Oct 01 10:46:45 volumio kernel: codec:vdec_request_irq ffffffc00184fe50, vsync Oct 01 10:46:45 volumio kernel: codec:create_ge2d_work_queue video task ok Oct 01 10:46:45 volumio kernel: rdma_register, rdma_table_addr ffffff8000748000 rdma_table_addr_phy 7b058000 reg_buf ffffffc0739f8000 Oct 01 10:46:45 volumio kernel: rdma_register success, handle 3 table_size 32768 Oct 01 10:46:45 volumio kernel: video_rdma:rdma_init video rdma handle = 3. Oct 01 10:46:45 volumio kernel: codec:used fix clk for vdec clk source! Oct 01 10:46:45 volumio kernel: codec:register clk_set_setting cpu[33] Oct 01 10:46:45 volumio kernel: vpu_init Oct 01 10:46:45 volumio kernel: The chip is not support hevc encoder Oct 01 10:46:45 volumio kernel: DI: di_module_init ok. Oct 01 10:46:45 volumio kernel: DI: di_module_init: major 232 Oct 01 10:46:45 volumio kernel: DI: di_probe Oct 01 10:46:45 volumio kernel: deinterlace has 1 memory regions Oct 01 10:46:45 volumio kernel: deinterlace deinterlace.28: assigned reserved memory node linux,di_cma ok Oct 01 10:46:45 volumio kernel: DI-di_probe: flag_cma: 1 Oct 01 10:46:45 volumio kernel: DI: CMA size 0x2400000. Oct 01 10:46:45 volumio kernel: di_irq:78,timerc_irq:38 Oct 01 10:46:45 volumio kernel: rdma_register, rdma_table_addr ffffff8000751000 rdma_table_addr_phy 7b04e000 reg_buf ffffffc073a14000 Oct 01 10:46:45 volumio kernel: rdma_register success, handle 4 table_size 8192 Oct 01 10:46:45 volumio kernel: DI: di_probe allocate rdma channel 4. Oct 01 10:46:45 volumio kernel: DI: DI hw version 2. Oct 01 10:46:46 volumio kernel: 0x000000db:Y=db,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000dc:Y=dc,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000dd:Y=dd,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000de:Y=de,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000df:Y=df,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000e0:Y=e0,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000e1:Y=e1,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000e2:Y=e2,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000e3:Y=e3,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f0:Y=f0,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f1:Y=f1,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f2:Y=f2,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f3:Y=f3,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f4:Y=f4,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f5:Y=f5,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f6:Y=f6,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f7:Y=f7,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f8:Y=f8,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000f9:Y=f9,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000fa:Y=fa,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000fb:Y=fb,U=0,V=0 Oct 01 10:46:46 volumio kernel: 0x000000fc:Y=fc,U=0,V=0 Oct 01 10:46:46 volumio kernel: efusekeynum: 4 Oct 01 10:46:46 volumio kernel: efusekeyname: mac offset: 0 size: 6 Oct 01 10:46:46 volumio kernel: efusekeyname: mac_bt offset: 6 size: 6 Oct 01 10:46:46 volumio kernel: efusekeyname: mac_wifi offset: 12 size: 6 Oct 01 10:46:46 volumio kernel: efusekeyname: usid offset: 18 size: 16 Oct 01 10:46:46 volumio kernel: efuse efuse.10: probe OK! Oct 01 10:46:46 volumio kernel: key unify config unifykey-num is 14 Oct 01 10:46:46 volumio kernel: key unify fact unifykey-num is 14 Oct 01 10:46:46 volumio kernel: aml_unifykeys_probe:1280=============unifykey_devno:e600000 Oct 01 10:46:46 volumio kernel: [39B blob data] Oct 01 10:46:46 volumio kernel: [26B blob data] Oct 01 10:46:46 volumio kernel: PPMGRDRV: warn: ppmgr module init func called Oct 01 10:46:46 volumio kernel: PPMGRDRV: err: ppmgr_driver_probe called Oct 01 10:46:46 volumio kernel: ppmgr has 1 memory regions Oct 01 10:46:46 volumio kernel: PPMGRDRV: info: ppmgr_dev major:229 Oct 01 10:46:46 volumio kernel: PPMGRDRV: info: ppmgr_probe done Oct 01 10:46:46 volumio kernel: amlogic rfkill init Oct 01 10:46:46 volumio kernel: enter bt_probe of_node Oct 01 10:46:46 volumio kernel: not get gpio_en Oct 01 10:46:46 volumio kernel: power on valid level is high Oct 01 10:46:46 volumio kernel: bt: power_on_pin_OD = 0; Oct 01 10:46:46 volumio kernel: audio_dsp: [dsp]register dsp to char divece(257) Oct 01 10:46:46 volumio kernel: amaudio: amaudio: driver amaudio succuess! Oct 01 10:46:46 volumio kernel: amlvid:err: amlvideo_init called Oct 01 10:46:46 volumio kernel: amlvid:err: amlvideo_create_instance called Oct 01 10:46:46 volumio kernel: amlvid:err: v4l2_dev.name=:amlvideo-000 Oct 01 10:46:46 volumio kernel: amlvideo-000: V4L2 device registered as video10 Oct 01 10:46:46 volumio kernel: vdin_drv_init: major 228 Oct 01 10:46:46 volumio kernel: rdma_register, rdma_table_addr ffffff8000754000 rdma_table_addr_phy 7b051000 reg_buf ffffffc073ac2600 Oct 01 10:46:46 volumio kernel: rdma_register success, handle 5 table_size 512 Oct 01 10:46:46 volumio kernel: vdin_drv_probe:vdin.0 rdma hanld 5. Oct 01 10:46:46 volumio kernel: vdin memory resource undefined!! Oct 01 10:46:46 volumio kernel: vdin0 cma_mem_size = 16 MB Oct 01 10:46:46 volumio kernel: vdin0 irq: 115 rdma irq: 2 Oct 01 10:46:46 volumio kernel: vdin_drv_probe: fclk_div5 is 400 MHZ Oct 01 10:46:46 volumio kernel: vdin_drv_probe: vdin[0] clock is 50 MHZ Oct 01 10:46:46 volumio kernel: vdin_drv_probe: driver initialized ok Oct 01 10:46:46 volumio kernel: rdma_register, rdma_table_addr ffffff8000756000 rdma_table_addr_phy 7b052000 reg_buf ffffffc073ac2a00 Oct 01 10:46:46 volumio kernel: rdma_register success, handle 6 table_size 512 Oct 01 10:46:46 volumio kernel: vdin_drv_probe:vdin.1 rdma hanld 6. Oct 01 10:46:46 volumio kernel: vdin1 has 1 memory regions Oct 01 10:46:46 volumio kernel: vdin vdin1.30: assigned reserved memory node linux,vdin1_cma ok Oct 01 10:46:46 volumio kernel: vdin memory resource done. Oct 01 10:46:46 volumio kernel: vdin1 cma_mem_size = 16 MB Oct 01 10:46:46 volumio kernel: vdin1 irq: 117 rdma irq: 4 Oct 01 10:46:46 volumio kernel: vdin_drv_probe: fclk_div5 is 400 MHZ Oct 01 10:46:46 volumio kernel: vdin_drv_probe: vdin[1] clock is 50 MHZ Oct 01 10:46:46 volumio kernel: vdin_drv_probe: driver initialized ok Oct 01 10:46:46 volumio kernel: vdin_drv_init: vdin driver init done Oct 01 10:46:46 volumio kernel: tvafe_drv_init: major 227 Oct 01 10:46:46 volumio kernel: tvafe: tvafe_init. Oct 01 10:46:46 volumio kernel: [RX]-hdmirx: hdmirx_init. Oct 01 10:46:46 volumio kernel: ESM HLD: Initializing... Oct 01 10:46:46 volumio kernel: ESM HLD: Registering device range 'esm_rx'... Oct 01 10:46:46 volumio kernel: ESM HLD: Device range 'esm_rx' has been registered. Oct 01 10:46:46 volumio kernel: ESM HLD: Creating class /sys/class/elliptic_rx... Oct 01 10:46:46 volumio kernel: ESM HLD: The class /sys/class/elliptic_rx has been created. Oct 01 10:46:46 volumio kernel: ESM HLD: Creating device 'esm_rx'... Oct 01 10:46:46 volumio kernel: ESM HLD: Device 'esm_rx' has been created. Oct 01 10:46:46 volumio kernel: ESM HLD: Done initializing the HLD driver. Oct 01 10:46:46 volumio kernel: module init Oct 01 10:46:46 volumio kernel: VECM probe start Oct 01 10:46:46 volumio kernel: aml_vecm_probe: ok Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: initialized by BL30 Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: calibration start: Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: nominal=0, value=5 Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: nominal=256, value=230 Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: nominal=512, value=460 Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: nominal=768, value=691 Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: nominal=1023, value=918 Oct 01 10:46:46 volumio kernel: saradc c1108680.saradc: calibration end: coef=4549 Oct 01 10:46:46 volumio kernel: remote: Driver init Oct 01 10:46:46 volumio kernel: remote: platform_data g_remote_ao_offset=580 Oct 01 10:46:46 volumio kernel: remote: platform_data irq =228 Oct 01 10:46:46 volumio kernel: [30B blob data] Oct 01 10:46:46 volumio kernel: [38B blob data] Oct 01 10:46:46 volumio kernel: input: aml_keypad as /devices/meson-remote.12/input/input0 Oct 01 10:46:46 volumio kernel: [41B blob data] Oct 01 10:46:46 volumio kernel: remote: set_remote_mode[78] Oct 01 10:46:46 volumio kernel: [32B blob data] Oct 01 10:46:46 volumio kernel: remote: physical address:0x73ace000 Oct 01 10:46:46 volumio kernel: gpio-key gpio_keypad.51: gpio_key: 0 name(power) pin() Oct 01 10:46:46 volumio kernel: gpio-key gpio_keypad.51: amlogic_gpio_name_map_num pin 0!:: Oct 01 10:46:46 volumio kernel: gpio-key gpio_keypad.51: gpio_key: 0 power(147) Oct 01 10:46:46 volumio kernel: gpio-key gpio_keypad.51: start setup_timer Oct 01 10:46:46 volumio kernel: gpio-key gpio_keypad.51: power key(116) registed. Oct 01 10:46:46 volumio kernel: input: gpio_keypad as /devices/gpio_keypad.51/input/input1 Oct 01 10:46:46 volumio kernel: gpio-key gpio_keypad.51: gpio keypad register input device completed. Oct 01 10:46:46 volumio kernel: input input1: gpio keypad major:222 Oct 01 10:46:46 volumio kernel: Sensor: lsm303d_init Oct 01 10:46:46 volumio kernel: Sensor: stk8313_init Oct 01 10:46:46 volumio kernel: Sensor: stk831x_init Oct 01 10:46:46 volumio kernel: Sensor: mxc622x_acc_init Oct 01 10:46:46 volumio kernel: Sensor: mxc6255xc_acc_init Oct 01 10:46:46 volumio kernel: Sensor: l3gd20_gyr_init Oct 01 10:46:46 volumio kernel: cpuinfo: probe done Oct 01 10:46:46 volumio kernel: defendkey c8834500.defendkey: defendkey_devno:dc00000 Oct 01 10:46:46 volumio kernel: defendkey c8834500.defendkey: defendkey: device defendkey created ok Oct 01 10:46:46 volumio kernel: defendkey: driver init Oct 01 10:46:46 volumio kernel: input: cec_input as /devices/virtual/input/input2 Oct 01 10:46:46 volumio kernel: cectx aocec: aml_cec_probe(): no hdmirx reg resource Oct 01 10:46:46 volumio kernel: cectx aocec: aml_cec_probe(): no hhi reg resource Oct 01 10:46:46 volumio kernel: cectx aocec: aml_cec_probe(): can't find hdmirx Oct 01 10:46:46 volumio kernel: input input1: key 116 up Oct 01 10:46:46 volumio kernel: cec: wake up flag:0 Oct 01 10:46:46 volumio kernel: aml_codec_T9015 c8832000.t9015: aml_T9015_audio_codec_probe Oct 01 10:46:46 volumio kernel: aml_pcm_dai: pcm mode detection =1 Oct 01 10:46:46 volumio kernel: aml_spdif_dai: enterd aml_spdif_play,set_clock:-1,sample_rate=4 Oct 01 10:46:46 volumio kernel: aml_spdif_dai: set normal 512 fs /4 fs Oct 01 10:46:46 volumio kernel: aml_audio_hw: IEC958 16bit Oct 01 10:46:46 volumio kernel: hdmitx: audio: aout notify rate 48000 Oct 01 10:46:46 volumio kernel: hdmitx: audio: aout notify size 16 Oct 01 10:46:46 volumio kernel: hdmitx: audio: aout notify format CT_PCM Oct 01 10:46:46 volumio kernel: aml_snd_card: headphone detection disable=1 Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: T9015-audio-hifi <-> I2S.43 mapping ok Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: dit-hifi <-> SPDIF.44 mapping ok Oct 01 10:46:46 volumio kernel: aml_pcm: enter aml_pcm_new dai->name: PCM.45 dai->id: 0 Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: pcm2bt-pcm <-> PCM.45 mapping ok Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: ASoC: no source widget found for LOUTL Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: ASoC: Failed to add route LOUTL -> direct -> Ext Spk Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: ASoC: no source widget found for LOUTR Oct 01 10:46:46 volumio kernel: aml_snd_m8_card aml_m8_snd.50: ASoC: Failed to add route LOUTR -> direct -> Ext Spk Oct 01 10:46:46 volumio kernel: aml_snd_card: audio use jtag pinmux as i2s output, read val =0 Oct 01 10:46:46 volumio kernel: GACT probability NOT on Oct 01 10:46:46 volumio kernel: Mirror/redirect action on Oct 01 10:46:46 volumio kernel: u32 classifier Oct 01 10:46:46 volumio kernel: Actions configured Oct 01 10:46:46 volumio kernel: Netfilter messages via NETLINK v0.30. Oct 01 10:46:46 volumio kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Oct 01 10:46:46 volumio kernel: ctnetlink v0.93: registering with nfnetlink. Oct 01 10:46:46 volumio kernel: xt_time: kernel timezone is -0000 Oct 01 10:46:46 volumio kernel: ipip: IPv4 over IPv4 tunneling driver Oct 01 10:46:46 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Oct 01 10:46:46 volumio kernel: arp_tables: (C) 2002 David S. Miller Oct 01 10:46:46 volumio kernel: TCP: cubic registered Oct 01 10:46:46 volumio kernel: Initializing XFRM netlink socket Oct 01 10:46:46 volumio kernel: NET: Registered protocol family 10 Oct 01 10:46:46 volumio kernel: mip6: Mobile IPv6 Oct 01 10:46:46 volumio kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team Oct 01 10:46:46 volumio kernel: sit: IPv6 over IPv4 tunneling driver Oct 01 10:46:46 volumio kernel: NET: Registered protocol family 17 Oct 01 10:46:46 volumio kernel: NET: Registered protocol family 15 Oct 01 10:46:46 volumio kernel: Bridge firewalling registered Oct 01 10:46:46 volumio kernel: l2tp_core: L2TP core driver, V2.0 Oct 01 10:46:46 volumio kernel: l2tp_ip: L2TP IP encapsulation support (L2TPv3) Oct 01 10:46:46 volumio kernel: l2tp_netlink: L2TP netlink interface Oct 01 10:46:46 volumio kernel: l2tp_eth: L2TP ethernet pseudowire support (L2TPv3) Oct 01 10:46:46 volumio kernel: l2tp_debugfs: L2TP debugfs support Oct 01 10:46:46 volumio kernel: l2tp_ip6: L2TP IP encapsulation support for IPv6 (L2TPv3) Oct 01 10:46:46 volumio kernel: NET: Registered protocol family 35 Oct 01 10:46:46 volumio kernel: Key type dns_resolver registered Oct 01 10:46:46 volumio kernel: Registering SWP/SWPB emulation handler Oct 01 10:46:46 volumio kernel: bio: create slab at 1 Oct 01 10:46:46 volumio kernel: Btrfs loaded Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running btrfs free space cache tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running extent only tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running bitmap only tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running bitmap and extent tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Free space cache tests finished Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running extent buffer operation tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running btrfs_split_item tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running find delalloc tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running btrfs_get_extent tests Oct 01 10:46:46 volumio kernel: BTRFS: selftest: Running hole first btrfs_get_extent test Oct 01 10:46:46 volumio kernel: regulator-dummy: disabling Oct 01 10:46:46 volumio kernel: Mali DRM initialize, driver name: mali_drm, version 2.1 Oct 01 10:46:46 volumio kernel: [drm] Initialized mali_drm 2.1.1 20140306 on minor 0 Oct 01 10:46:46 volumio kernel: Mali DRM initialize, driver name: mali_drm, version 2.1 Oct 01 10:46:46 volumio kernel: [drm] Initialized mali_drm 2.1.1 20140306 on minor 1 Oct 01 10:46:46 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Oct 01 10:46:46 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 Oct 01 10:46:46 volumio kernel: xhci-hcd xhci-hcd.0.auto: irq 62, io mem 0xc9000000 Oct 01 10:46:46 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Oct 01 10:46:46 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 01 10:46:46 volumio kernel: usb usb1: Product: xHCI Host Controller Oct 01 10:46:46 volumio kernel: usb usb1: Manufacturer: Linux 3.14.29 xhci-hcd Oct 01 10:46:46 volumio kernel: usb usb1: SerialNumber: xhci-hcd.0.auto Oct 01 10:46:46 volumio kernel: hub 1-0:1.0: USB hub found Oct 01 10:46:46 volumio kernel: hub 1-0:1.0: 2 ports detected Oct 01 10:46:46 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Oct 01 10:46:46 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 Oct 01 10:46:46 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 Oct 01 10:46:46 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 01 10:46:46 volumio kernel: usb usb2: Product: xHCI Host Controller Oct 01 10:46:46 volumio kernel: usb usb2: Manufacturer: Linux 3.14.29 xhci-hcd Oct 01 10:46:46 volumio kernel: usb usb2: SerialNumber: xhci-hcd.0.auto Oct 01 10:46:46 volumio kernel: hub 2-0:1.0: USB hub found Oct 01 10:46:46 volumio kernel: hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19) Oct 01 10:46:46 volumio kernel: console [netcon0] enabled Oct 01 10:46:46 volumio kernel: netconsole: network logging started Oct 01 10:46:46 volumio kernel: aml_vrtc rtc.35: setting system clock to 2024-10-01 10:46:37 UTC (1727779597) Oct 01 10:46:46 volumio kernel: cpufreq-scpi cpufreq-meson.7: enter cpufreq Oct 01 10:46:46 volumio kernel: cpufreq-scpi cpufreq-meson.7: probe cpufreq okay Oct 01 10:46:46 volumio kernel: hdmirx_ext: [hdmiin_drv_init] ver: Jul042016.1 Oct 01 10:46:46 volumio kernel: gxbb_pm: enter meson_pm_probe! Oct 01 10:46:46 volumio kernel: gxbb_pm: meson_pm_probe done Oct 01 10:46:46 volumio kernel: cpucore_cooling_register, max_cpu_core_num:4 Oct 01 10:46:46 volumio kernel: aml_istbt_dev_ready NULL Oct 01 10:46:46 volumio kernel: ALSA device list: Oct 01 10:46:46 volumio kernel: #0: AML-M8AUDIO Oct 01 10:46:46 volumio kernel: Freeing unused kernel memory: 1236K (ffffffc001f2d000 - ffffffc002062000) Oct 01 10:46:46 volumio kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Oct 01 10:46:46 volumio initramfs: Boot delay will be 5 seconds to give kernel load a headstart Oct 01 10:46:46 volumio kernel: usb 1-1: New USB device found, idVendor=152a, idProduct=8750 Oct 01 10:46:46 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 01 10:46:46 volumio kernel: usb 1-1: Product: E50 Oct 01 10:46:46 volumio kernel: usb 1-1: Manufacturer: Topping Oct 01 10:46:46 volumio kernel: hid-generic 0003:152A:8750.0001: hiddev0,hidraw0: USB HID v1.10 Device [Topping E50] on usb-xhci-hcd.0.auto-1/input3 Oct 01 10:46:46 volumio kernel: usb 1-2: new high-speed USB device number 3 using xhci-hcd Oct 01 10:46:46 volumio kernel: usb 1-2: New USB device found, idVendor=152d, idProduct=0578 Oct 01 10:46:46 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 01 10:46:46 volumio kernel: usb 1-2: Product: USB 3.0 Oct 01 10:46:46 volumio kernel: usb 1-2: Manufacturer: ORICO Oct 01 10:46:46 volumio kernel: usb 1-2: SerialNumber: DD564198838A2 Oct 01 10:46:46 volumio kernel: usb-storage 1-2:1.0: USB Mass Storage device detected Oct 01 10:46:46 volumio kernel: scsi0 : usb-storage 1-2:1.0 Oct 01 10:46:46 volumio kernel: scsi 0:0:0:0: Direct-Access ORICO USB 3.0 0209 PQ: 0 ANSI: 6 Oct 01 10:46:46 volumio kernel: sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB) Oct 01 10:46:46 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 01 10:46:46 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 01 10:46:46 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 47 00 00 08 Oct 01 10:46:46 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 01 10:46:46 volumio initramfs: Booting Volumio for Amlogic Oct 01 10:46:46 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Oct 01 10:46:46 volumio initramfs: IMGPART=/dev/mmcblk1p2 Oct 01 10:46:46 volumio initramfs: IMGFILE=/volumio_current.sqsh Oct 01 10:46:46 volumio initramfs: DATAPART=/dev/mmcblk1p3 Oct 01 10:46:46 volumio initramfs: BOOTPART=/dev/mmcblk1p1 Oct 01 10:46:46 volumio initramfs: BOOTCONFIG=empty Oct 01 10:46:46 volumio kernel: EXT4-fs (mmcblk1p2): recovery complete Oct 01 10:46:46 volumio kernel: EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) Oct 01 10:46:46 volumio initramfs: Checking for USB updates if you did not boot from USB... Oct 01 10:46:46 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Oct 01 10:46:46 volumio kernel: random: nonblocking pool is initialized Oct 01 10:46:46 volumio kernel: FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 01 10:46:46 volumio kernel: FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 01 10:46:46 volumio kernel: EXT4-fs (mmcblk1p3): recovery complete Oct 01 10:46:46 volumio kernel: EXT4-fs (mmcblk1p3): mounted filesystem with ordered data mode. Opts: (null) Oct 01 10:46:46 volumio initramfs: With Option: Oct 01 10:46:46 volumio initramfs: Incompatible fstab, no entry found for /boot Oct 01 10:46:46 volumio initramfs: found fstab entry with device for /boot Oct 01 10:46:46 volumio kernel: FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 01 10:46:46 volumio initramfs: VOLUMIO_VERSION="2.857" Oct 01 10:46:46 volumio initramfs: Finish initramfs, continue booting Volumio Oct 01 10:46:46 volumio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Oct 01 10:46:46 volumio systemd[1]: Detected architecture 'arm64'. Oct 01 10:46:46 volumio systemd[1]: Set hostname to . Oct 01 10:46:46 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Oct 01 10:46:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Oct 01 10:46:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Oct 01 10:46:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Oct 01 10:46:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Oct 01 10:46:46 volumio systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Oct 01 10:46:46 volumio systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Oct 01 10:46:46 volumio kernel: sda: sda1 Oct 01 10:46:46 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 01 10:46:46 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 01 10:46:46 volumio systemd[1]: Expecting device dev-ttyS0.device... Oct 01 10:46:46 volumio systemd[1]: Starting Remote File Systems (Pre). Oct 01 10:46:46 volumio systemd[1]: Reached target Remote File Systems (Pre). Oct 01 10:46:46 volumio systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point. Oct 01 10:46:46 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 01 10:46:46 volumio systemd[1]: Starting Encrypted Volumes. Oct 01 10:46:46 volumio systemd[1]: Reached target Encrypted Volumes. Oct 01 10:46:46 volumio systemd[1]: Expecting device dev-disk-by\x2dlabel-BOOT.device... Oct 01 10:46:46 volumio systemd[1]: Starting Root Slice. Oct 01 10:46:46 volumio systemd[1]: Created slice Root Slice. Oct 01 10:46:46 volumio systemd[1]: Starting User and Session Slice. Oct 01 10:46:46 volumio systemd[1]: Created slice User and Session Slice. Oct 01 10:46:46 volumio systemd[1]: Starting /dev/initctl Compatibility Named Pipe. Oct 01 10:46:46 volumio systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. Oct 01 10:46:46 volumio systemd[1]: Starting Delayed Shutdown Socket. Oct 01 10:46:46 volumio systemd[1]: Listening on Delayed Shutdown Socket. Oct 01 10:46:46 volumio systemd[1]: Starting Journal Socket (/dev/log). Oct 01 10:46:46 volumio systemd[1]: Listening on Journal Socket (/dev/log). Oct 01 10:46:46 volumio systemd[1]: Starting udev Control Socket. Oct 01 10:46:46 volumio systemd[1]: Listening on udev Control Socket. Oct 01 10:46:46 volumio systemd[1]: Starting udev Kernel Socket. Oct 01 10:46:46 volumio systemd[1]: Listening on udev Kernel Socket. Oct 01 10:46:46 volumio systemd[1]: Starting Journal Socket. Oct 01 10:46:46 volumio systemd[1]: Listening on Journal Socket. Oct 01 10:46:46 volumio systemd[1]: Starting System Slice. Oct 01 10:46:46 volumio systemd[1]: Created slice System Slice. Oct 01 10:46:46 volumio systemd[1]: Starting system-systemd\x2dfsck.slice. Oct 01 10:46:46 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Oct 01 10:46:46 volumio systemd[1]: Starting dynamicswap service... Oct 01 10:46:46 volumio systemd[1]: Started dynamicswap service. Oct 01 10:46:46 volumio systemd[1]: Starting Swap. Oct 01 10:46:46 volumio systemd[1]: Reached target Swap. Oct 01 10:46:46 volumio systemd[1]: Starting system-getty.slice. Oct 01 10:46:46 volumio systemd[1]: Created slice system-getty.slice. Oct 01 10:46:46 volumio systemd[1]: Starting system-serial\x2dgetty.slice. Oct 01 10:46:46 volumio systemd[1]: Created slice system-serial\x2dgetty.slice. Oct 01 10:46:46 volumio systemd[1]: Starting Restore / save the current clock... Oct 01 10:46:46 volumio systemd[1]: Mounting Huge Pages File System... Oct 01 10:46:46 volumio systemd[1]: Mounting POSIX Message Queue File System... Oct 01 10:46:46 volumio systemd[1]: Starting Create list of required static device nodes for the current kernel... Oct 01 10:46:46 volumio systemd[1]: Mounting Debug File System... Oct 01 10:46:46 volumio systemd[1]: Started Set Up Additional Binary Formats. Oct 01 10:46:46 volumio systemd[1]: Starting Load Kernel Modules... Oct 01 10:46:46 volumio systemd[1]: Starting udev Coldplug all Devices... Oct 01 10:46:46 volumio systemd[1]: Starting Journal Service... Oct 01 10:46:46 volumio systemd[1]: Started Journal Service. Oct 01 10:46:46 volumio kernel: cfg80211: Calling CRDA to update world regulatory domain Oct 01 10:46:46 volumio kernel: dhd_module_init: in Dongle Host Driver, version 1.363.59.144.9 (r) Oct 01 10:46:46 volumio kernel: ======== dhd_wlan_init_plat_data ======== Oct 01 10:46:46 volumio kernel: dhd_wlan_init_gpio: GPIO(WL_REG_ON) = -1 Oct 01 10:46:46 volumio kernel: dhd_wlan_init_gpio: GPIO(WL_HOST_WAKE) = -1 Oct 01 10:46:46 volumio kernel: dhd_wlan_init_gpio: host_oob_irq: 100 Oct 01 10:46:46 volumio kernel: dhd_wlan_init_gpio: host_oob_irq_flags=0x414 Oct 01 10:46:46 volumio kernel: dhd_wifi_platform_load: Enter Oct 01 10:46:46 volumio kernel: Power-up adapter 'DHD generic adapter' Oct 01 10:46:46 volumio kernel: wifi_platform_set_power = 1 Oct 01 10:46:46 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Oct 01 10:46:46 volumio kernel: aml_wifi wifi.36: [extern_wifi_set_enable] WIFI Disable! 240 Oct 01 10:46:46 volumio systemd-journal[2896]: Journal started Oct 01 10:46:46 volumio systemd[1]: Starting Slices. Oct 01 10:46:46 volumio systemd[1]: Reached target Slices. Oct 01 10:46:46 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Oct 01 10:46:46 volumio systemd[1]: Mounted Debug File System. Oct 01 10:46:46 volumio systemd[1]: Mounted POSIX Message Queue File System. Oct 01 10:46:46 volumio systemd[1]: Mounted Huge Pages File System. Oct 01 10:46:46 volumio systemd[1]: Started Restore / save the current clock. Oct 01 10:46:46 volumio systemd[1]: Started Create list of required static device nodes for the current kernel. Oct 01 10:46:46 volumio systemd[1]: Started Remount Root and Kernel File Systems. Oct 01 10:46:45 volumio systemd-modules-load[2894]: Module 'fuse' is builtin Oct 01 10:46:46 volumio systemd[1]: Started Various fixups to make systemd work better on Debian. Oct 01 10:46:46 volumio fake-hwclock[2875]: Current system time: 2024-10-01 10:46:45 Oct 01 10:46:46 volumio fake-hwclock[2875]: fake-hwclock saved clock information is in the past: 2024-10-01 10:46:16 Oct 01 10:46:46 volumio fake-hwclock[2875]: To set system time to this saved clock anyway, use "force" Oct 01 10:46:46 volumio systemd[1]: Started Create Static Device Nodes in /dev. Oct 01 10:46:46 volumio systemd[1]: Starting udev Kernel Device Manager... Oct 01 10:46:46 volumio systemd[1]: Starting Local File Systems (Pre). Oct 01 10:46:46 volumio systemd[1]: Reached target Local File Systems (Pre). Oct 01 10:46:46 volumio systemd[1]: Mounting /tmp... Oct 01 10:46:46 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 01 10:46:46 volumio systemd[1]: Mounting /var/spool/cups... Oct 01 10:46:46 volumio systemd-udevd[3978]: starting version 215 Oct 01 10:46:46 volumio systemd[1]: Mounting /var/log... Oct 01 10:46:46 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 01 10:46:46 volumio systemd[1]: Mounted /tmp. Oct 01 10:46:46 volumio systemd[1]: Mounted /var/spool/cups. Oct 01 10:46:46 volumio systemd[1]: Mounted /var/log. Oct 01 10:46:46 volumio systemd[1]: Started udev Kernel Device Manager. Oct 01 10:46:46 volumio systemd[1]: Started udev Coldplug all Devices. Oct 01 10:46:46 volumio systemd[1]: Started Show Plymouth Boot Screen. Oct 01 10:46:46 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 01 10:46:46 volumio systemd[1]: Starting Dispatch Password Requests to Console Directory Watch. Oct 01 10:46:46 volumio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 01 10:46:46 volumio systemd[1]: Starting Paths. Oct 01 10:46:46 volumio systemd[1]: Reached target Paths. Oct 01 10:46:46 volumio systemd[1]: Starting Copy rules generated while the root was ro... Oct 01 10:46:46 volumio kernel: aml_wifi wifi.36: [extern_wifi_set_enable] WIFI Enable! 240 Oct 01 10:46:46 volumio systemd[1]: Mounting /var/spool/cups/tmp... Oct 01 10:46:46 volumio systemd[1]: Started Copy rules generated while the root was ro. Oct 01 10:46:46 volumio systemd[1]: Mounted /var/spool/cups/tmp. Oct 01 10:46:46 volumio systemd[1]: Starting Local File Systems. Oct 01 10:46:46 volumio systemd[1]: Reached target Local File Systems. Oct 01 10:46:46 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 01 10:46:46 volumio systemd[1]: Starting Remote File Systems. Oct 01 10:46:46 volumio systemd[1]: Reached target Remote File Systems. Oct 01 10:46:46 volumio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Oct 01 10:46:46 volumio systemd[1]: Starting Create Volatile Files and Directories... Oct 01 10:46:46 volumio systemd[1]: Mounted FUSE Control File System. Oct 01 10:46:46 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 01 10:46:46 volumio systemd[1]: Started Create Volatile Files and Directories. Oct 01 10:46:46 volumio systemd[1]: Found device /dev/ttyS0. Oct 01 10:46:46 volumio systemd[1]: Starting Sound Card. Oct 01 10:46:46 volumio systemd[1]: Reached target Sound Card. Oct 01 10:46:46 volumio systemd[1]: Starting system-systemd\x2drfkill.slice. Oct 01 10:46:46 volumio systemd[1]: Created slice system-systemd\x2drfkill.slice. Oct 01 10:46:46 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Oct 01 10:46:46 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 01 10:46:46 volumio kernel: BT_RADIO going: on Oct 01 10:46:46 volumio kernel: BCM_BT: going ON Oct 01 10:46:46 volumio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Oct 01 10:46:46 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 01 10:46:46 volumio kernel: wifi_platform_bus_enumerate device present 1 Oct 01 10:46:46 volumio kernel: ======== Card detection to detect SDIO card! ======== Oct 01 10:46:46 volumio systemd[1]: Starting system-ifup.slice. Oct 01 10:46:46 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Oct 01 10:46:46 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Oct 01 10:46:46 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Oct 01 10:46:46 volumio systemd[1]: Created slice system-ifup.slice. Oct 01 10:46:46 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Oct 01 10:46:46 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Oct 01 10:46:46 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:52 Oct 01 10:46:46 volumio kernel: [aml_sd_emmc_irq] sdio: resp_timeout,vstat:0xa3ff2800,virqc:3fff Oct 01 10:46:46 volumio kernel: [aml_host_bus_fsm_show] sdio: err: wait for irq service, bus_fsm:0x8 Oct 01 10:46:46 volumio kernel: aml_sd_emmc_data_thread 2639 sdio: cmd:8 Oct 01 10:46:46 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Oct 01 10:46:46 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Oct 01 10:46:46 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Oct 01 10:46:46 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Oct 01 10:46:47 volumio kernel: sdio: new high speed SDIO card at address 0001 Oct 01 10:46:47 volumio kernel: sdio: clock 50000000, 4-bit-bus-width Oct 01 10:46:47 volumio kernel: [sdio_reinit] finish Oct 01 10:46:47 volumio systemd[1]: Found device /dev/disk/by-label/BOOT. Oct 01 10:46:47 volumio systemd-modules-load[2894]: Inserted module 'dhd' Oct 01 10:46:47 volumio systemd-modules-load[2894]: Failed to find module 'snd_soc_pcm5102' Oct 01 10:46:47 volumio systemd-modules-load[2894]: Failed to find module 'snd_soc_odroid_dac' Oct 01 10:46:47 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Oct 01 10:46:47 volumio kernel: bcmsdh_register: register client driver Oct 01 10:46:47 volumio kernel: bcmsdh_sdmmc: bcmsdh_sdmmc_probe Enter Oct 01 10:46:47 volumio kernel: bcmsdh_sdmmc: bcmsdh_sdmmc_probe Enter Oct 01 10:46:47 volumio kernel: bus num (host idx)=0, slot num (rca)=1 Oct 01 10:46:47 volumio kernel: found adapter info 'DHD generic adapter' Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 7 bcmdhd_mem_prealloc: wlan_mem_array[section].mem_ptr=ffffffc0020fc8b8, size=139264 Oct 01 10:46:47 volumio kernel: success alloc section 3, size 139264 Oct 01 10:46:47 volumio kernel: alloc static buf at ffffffc076100000! Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 1 bcmdhd_mem_prealloc: section=4, wlan_static_skb=ffffffc00257b928 Oct 01 10:46:47 volumio kernel: success alloc section 4, size 0 Oct 01 10:46:47 volumio kernel: sdioh_attach: set sd_f2_blocksize 256 Oct 01 10:46:47 volumio kernel: dhdsdio_probe : no mutex held. set lock Oct 01 10:46:47 volumio kernel: F1 signature read @0x18000000=0x16044330 Oct 01 10:46:47 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4330 rev:0x4 pkg:0x0 Oct 01 10:46:47 volumio kernel: DHD: dongle ram size is set to 294912(orig 294912) at 0x0 Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 4 bcmdhd_mem_prealloc: section=7, wlan_mem_array[4]=ffffffc076ae0000 Oct 01 10:46:47 volumio kernel: success alloc section 7, size 31312 Oct 01 10:46:47 volumio kernel: dhd_conf_set_chiprev: chip=0x4330, chiprev=4 Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 7 bcmdhd_mem_prealloc: wlan_mem_array[section].mem_ptr=ffffffc0020fc888, size=10544 Oct 01 10:46:47 volumio kernel: success alloc section 0, size 10544 Oct 01 10:46:47 volumio kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 2 bcmdhd_mem_prealloc: section=5, wlan_static_scan_buf0=ffffffc076b60000 Oct 01 10:46:47 volumio kernel: success alloc section 5, size 65536 Oct 01 10:46:47 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:10dc started Oct 01 10:46:47 volumio kernel: tsk Enter, tsk = 0xffffff800156ebb8 Oct 01 10:46:47 volumio kernel: wl_escan_attach: Enter Oct 01 10:46:47 volumio kernel: wl_escan_init: Enter Oct 01 10:46:47 volumio kernel: wl_create_event_handler(): thread:wl_escan_handler:10de started Oct 01 10:46:47 volumio kernel: dhd_attach(): thread:dhd_watchdog_thread:10df started Oct 01 10:46:47 volumio kernel: dhd_attach(): thread:dhd_dpc:10e0 started Oct 01 10:46:47 volumio kernel: dhd_attach(): thread:dhd_rxf:10e2 started Oct 01 10:46:47 volumio kernel: dhd_deferred_work_init: work queue initialized Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 7 bcmdhd_mem_prealloc: wlan_mem_array[section].mem_ptr=ffffffc0020fc898, size=10524 Oct 01 10:46:47 volumio kernel: success alloc section 1, size 10524 Oct 01 10:46:47 volumio kernel: Wifi: bcmdhd_mem_prealloc: 7 bcmdhd_mem_prealloc: wlan_mem_array[section].mem_ptr=ffffffc0020fc8a8, size=65536 Oct 01 10:46:47 volumio kernel: success alloc section 2, size 65536 Oct 01 10:46:47 volumio kernel: tsk Enter, tsk = 0xffffffc059d101a0 Oct 01 10:46:47 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) Oct 01 10:46:47 volumio kernel: Register interface [wlan0] MAC: e0:76:d0:7a:61:b2 Oct 01 10:46:47 volumio kernel: wl_event_handler: was terminated Oct 01 10:46:47 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:10dc terminated OK Oct 01 10:46:47 volumio kernel: dhd_wl_ioctl: returning as busstate=0 Oct 01 10:46:47 volumio kernel: bcmsdh_oob_intr_unregister: Enter Oct 01 10:46:47 volumio kernel: bcmsdh_oob_intr_unregister: irq is not registered Oct 01 10:46:47 volumio kernel: dhd_txglom_enable: enable 0 Oct 01 10:46:47 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Oct 01 10:46:47 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Oct 01 10:46:47 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Oct 01 10:46:47 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=1, txctl_tmo_fix=0 Oct 01 10:46:47 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Oct 01 10:46:47 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Oct 01 10:46:47 volumio kernel: wifi_platform_set_power = 0 Oct 01 10:46:47 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Oct 01 10:46:47 volumio kernel: dhdsdio_probe : the lock is released. Oct 01 10:46:47 volumio kernel: dhd_module_init: Exit err=0 Oct 01 10:46:47 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 01 10:46:47 volumio kernel: cfg80211: World regulatory domain updated: Oct 01 10:46:47 volumio kernel: cfg80211: DFS Master region: unset Oct 01 10:46:47 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) Oct 01 10:46:47 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm) Oct 01 10:46:47 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm) Oct 01 10:46:47 volumio kernel: aml_audio_i2s_mute_flag: flag=1 Oct 01 10:46:47 volumio kernel: aml_audio_set_spdif_mute: flag=0 Oct 01 10:46:47 volumio kernel: aml_snd_card: aml_m8_set_spk: aml_m8_spk_enabled=0 Oct 01 10:46:47 volumio systemd[1]: systemd-modules-load.service: main process exited, code=exited, status=1/FAILURE Oct 01 10:46:47 volumio systemd[1]: Failed to start Load Kernel Modules. Oct 01 10:46:47 volumio systemd[1]: Unit systemd-modules-load.service entered failed state. Oct 01 10:46:47 volumio kernel: usbcore: registered new interface driver brcmfmac Oct 01 10:46:48 volumio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Oct 01 10:46:48 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Oct 01 10:46:48 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill2... Oct 01 10:46:48 volumio systemd[1]: Mounting Configuration File System... Oct 01 10:46:48 volumio systemd[1]: Starting Apply Kernel Variables... Oct 01 10:46:48 volumio systemd[1]: Starting File System Check on /dev/disk/by-label/BOOT... Oct 01 10:46:48 volumio systemd[1]: Mounted Configuration File System. Oct 01 10:46:48 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Oct 01 10:46:48 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill2. Oct 01 10:46:48 volumio systemd[1]: Started Apply Kernel Variables. Oct 01 10:46:48 volumio systemd[1]: Started File System Check on /dev/disk/by-label/BOOT. Oct 01 10:46:48 volumio systemd[1]: Starting LSB: Raise network interfaces.... Oct 01 10:46:50 volumio networking[4388]: Configuring network interfaces...command failed: Input/output error (-5) Oct 01 10:46:50 volumio networking[4388]: command failed: Input/output error (-5) Oct 01 10:46:50 volumio networking[4388]: command failed: Input/output error (-5) Oct 01 10:46:51 volumio kernel: libphy: set driving length c Oct 01 10:46:51 volumio kernel: libphy: set PLL minimum jitter Oct 01 10:46:51 volumio kernel: libphy: set driving length c Oct 01 10:46:51 volumio kernel: libphy: set PLL minimum jitter Oct 01 10:46:51 volumio kernel: dhd_open: Enter ffffffc059172000 Oct 01 10:46:51 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) Oct 01 10:46:51 volumio kernel: wl_android_wifi_on in 1 Oct 01 10:46:51 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 Oct 01 10:46:51 volumio kernel: wifi_platform_set_power = 1 Oct 01 10:46:51 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Oct 01 10:46:51 volumio kernel: aml_wifi wifi.36: [extern_wifi_set_enable] WIFI Disable! 240 Oct 01 10:46:51 volumio kernel: aml_wifi wifi.36: [extern_wifi_set_enable] WIFI Enable! 240 Oct 01 10:46:51 volumio kernel: sdio_reset_comm(): Oct 01 10:46:51 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Oct 01 10:46:51 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Oct 01 10:46:51 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Oct 01 10:46:52 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Oct 01 10:46:52 volumio kernel: sdioh_start: set sd_f2_blocksize 256 Oct 01 10:46:52 volumio kernel: dhd_bus_devreset: == WLAN ON == Oct 01 10:46:52 volumio kernel: F1 signature read @0x18000000=0x16044330 Oct 01 10:46:52 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4330 rev:0x4 pkg:0x0 Oct 01 10:46:52 volumio kernel: DHD: dongle ram size is set to 294912(orig 294912) at 0x0 Oct 01 10:46:52 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (399 bytes) open success Oct 01 10:46:52 volumio kernel: dhd_conf_read_others: ccode = SG Oct 01 10:46:52 volumio kernel: dhd_conf_read_others: bcn_timeout = 20 Oct 01 10:46:52 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 1 Oct 01 10:46:52 volumio kernel: dhd_conf_read_others: mimo_bw_cap = 1 Oct 01 10:46:52 volumio kernel: dhd_conf_read_pm_params: PM = 0 Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=12 Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=0, name=nvram_ap6181.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=1, name=nvram_ap6210.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4330, chiprev=4, name=nvram_ap6330.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4339, chiprev=1, name=nvram_ap6335.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4335, chiprev=1, name=nvram_bcm4335.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa94c, chiprev=2, name=nvram_ap6234.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Oct 01 10:46:52 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4334, chiprev=3, name=nvram_bcm4334.txt Oct 01 10:46:52 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm40183b2.bin Oct 01 10:46:52 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6330.txt Oct 01 10:46:52 volumio kernel: Final clm_path=/lib/firmware/brcm/clm_bcmdhd.blob Oct 01 10:46:52 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt Oct 01 10:46:52 volumio kernel: dhd_set_bus_params: set use_rxchain 0 Oct 01 10:46:52 volumio kernel: dhd_set_bus_params: set txglomsize 6 Oct 01 10:46:52 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm40183b2.bin (239507 bytes) open success Oct 01 10:46:52 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6330.txt (1522 bytes) open success Oct 01 10:46:52 volumio kernel: [42B blob data] Oct 01 10:46:52 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Oct 01 10:46:52 volumio kernel: libphy: stmmac-0:08 - Link is Up - 100/Full Oct 01 10:46:52 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Oct 01 10:46:52 volumio kernel: bcmsdh_oob_intr_register: Enter Oct 01 10:46:52 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled Oct 01 10:46:52 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 Oct 01 10:46:52 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 Oct 01 10:46:52 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake Oct 01 10:46:52 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 Oct 01 10:46:52 volumio kernel: Disable tdls_auto_op failed. -1 Oct 01 10:46:52 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 Oct 01 10:46:52 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 Oct 01 10:46:52 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob Oct 01 10:46:52 volumio kernel: Firmware up: op_mode=0x0005, MAC=e0:76:d0:7a:61:b2 Oct 01 10:46:52 volumio kernel: dhd_conf_set_country: set country SG, revision 0 Oct 01 10:46:52 volumio kernel: Country code: SG (SG/0) Oct 01 10:46:52 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 Oct 01 10:46:52 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 Oct 01 10:46:52 volumio kernel: CONFIG-ERROR) dhd_conf_set_intiovar: mimo_bw_cap setting failed -23 Oct 01 10:46:52 volumio kernel: dhd_preinit_ioctls buf_key_b4_m4 set failed -23 Oct 01 10:46:52 volumio kernel: dhd_preinit_ioctls event_msgs_ext not support or version mismatch -23 Oct 01 10:46:52 volumio kernel: dhd_wl_ioctl_get_intiovar: get int iovar arp_version failed, ERR -23 Oct 01 10:46:52 volumio kernel: Firmware version = wl0: Jan 6 2014 15:11:29 version 5.90.195.89.13 FWID 01-72f124c5 Oct 01 10:46:52 volumio kernel: Driver: 1.363.59.144.9 (r) Firmware: wl0: Jan 6 2014 15:11:29 version 5.90.195.89.13 FWID 01-72f124c5 Oct 01 10:46:52 volumio kernel: dhd_preinit_ioctls failed -23 Oct 01 10:46:52 volumio kernel: dhd_txglom_enable: enable 0 Oct 01 10:46:52 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Oct 01 10:46:52 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Oct 01 10:46:52 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Oct 01 10:46:52 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=1, txctl_tmo_fix=0 Oct 01 10:46:52 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Oct 01 10:46:52 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Oct 01 10:46:52 volumio kernel: dhd_preinit_ioctls wl ampdu_hostreorder failed -23 Oct 01 10:46:52 volumio kernel: wl_android_wifi_on: Success Oct 01 10:46:52 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:119e started Oct 01 10:46:52 volumio kernel: tsk Enter, tsk = 0xffffff800156ebb8 Oct 01 10:46:53 volumio kernel: dhd_wlfc_enable: ret=0 Oct 01 10:46:53 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading vhtmode (-23) Oct 01 10:46:53 volumio kernel: dhd_open: Exit ret=0 Oct 01 10:46:53 volumio networking[4388]: done. Oct 01 10:46:53 volumio systemd[1]: Started LSB: Raise network interfaces.. Oct 01 10:46:53 volumio systemd[1]: Starting ifup for wlan0... Oct 01 10:46:53 volumio ifup[4512]: /sbin/ifup: interface wlan0 already configured Oct 01 10:46:53 volumio systemd[1]: Started ifup for wlan0. Oct 01 10:46:53 volumio systemd[1]: Starting ifup for eth0... Oct 01 10:46:53 volumio systemd[1]: Started ifup for eth0. Oct 01 10:46:53 volumio systemd[1]: Starting Network. Oct 01 10:46:53 volumio systemd[1]: Reached target Network. Oct 01 10:46:53 volumio systemd[1]: Starting Network is Online. Oct 01 10:46:53 volumio systemd[1]: Reached target Network is Online. Oct 01 10:46:53 volumio systemd[1]: Starting LSB: RPC portmapper replacement... Oct 01 10:46:53 volumio rpcbind[4522]: Starting rpcbind daemon.... Oct 01 10:46:53 volumio systemd[1]: Started LSB: RPC portmapper replacement. Oct 01 10:46:53 volumio systemd[1]: Starting RPC Port Mapper. Oct 01 10:46:53 volumio systemd[1]: Reached target RPC Port Mapper. Oct 01 10:46:53 volumio systemd[1]: Starting LSB: NFS support files common to client and server... Oct 01 10:46:53 volumio rpc.statd[4588]: Version 1.2.8 starting Oct 01 10:46:53 volumio sm-notify[4589]: Version 1.2.8 starting Oct 01 10:46:53 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Oct 01 10:46:53 volumio nfs-common[4582]: Starting NFS common utilities: statd idmapd. Oct 01 10:46:53 volumio systemd[1]: Started LSB: NFS support files common to client and server. Oct 01 10:46:53 volumio systemd[1]: Starting System Initialization. Oct 01 10:46:53 volumio systemd[1]: Reached target System Initialization. Oct 01 10:46:53 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Oct 01 10:46:53 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 01 10:46:53 volumio systemd[1]: Starting mpd.socket. Oct 01 10:46:53 volumio systemd[1]: Listening on mpd.socket. Oct 01 10:46:53 volumio systemd[1]: Starting D-Bus System Message Bus Socket. Oct 01 10:46:53 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 01 10:46:53 volumio systemd[1]: Starting Sockets. Oct 01 10:46:53 volumio systemd[1]: Reached target Sockets. Oct 01 10:46:53 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. Oct 01 10:46:53 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 01 10:46:53 volumio systemd[1]: Starting Timers. Oct 01 10:46:53 volumio systemd[1]: Reached target Timers. Oct 01 10:46:53 volumio systemd[1]: Started Manage Sound Card State (restore and store). Oct 01 10:46:53 volumio systemd[1]: Starting Restore Sound Card State... Oct 01 10:46:53 volumio systemd[1]: Starting Basic System. Oct 01 10:46:53 volumio systemd[1]: Reached target Basic System. Oct 01 10:46:53 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Oct 01 10:46:53 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 01 10:46:53 volumio systemd[1]: Starting cd.service... Oct 01 10:46:53 volumio alsactl[4607]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:DAC Digital Playback Volume:0' : No such file or directory Oct 01 10:46:53 volumio volumio[4609]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Oct 01 10:46:53 volumio systemd[1]: Started cd.service. Oct 01 10:46:53 volumio kernel: aml_audio_i2s_mute_flag: flag=1 Oct 01 10:46:53 volumio kernel: aml_audio_set_spdif_mute: flag=0 Oct 01 10:46:53 volumio kernel: aml_snd_card: aml_m8_set_spk: aml_m8_spk_enabled=0 Oct 01 10:46:53 volumio systemd[1]: Starting Volumio Iptables Module... Oct 01 10:46:53 volumio systemd[1]: Started Volumio Iptables Module. Oct 01 10:46:53 volumio systemd[1]: Starting mount disks automatically with standby... Oct 01 10:46:53 volumio systemd[1]: Started mount disks automatically with standby. Oct 01 10:46:53 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 01 10:46:53 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 01 10:46:53 volumio systemd[1]: Starting volumio-remote-updater.service... Oct 01 10:46:54 volumio systemd[1]: Started volumio-remote-updater.service. Oct 01 10:46:54 volumio systemd[1]: Starting Volumio Log Rotation Service... Oct 01 10:46:54 volumio systemd[1]: Started Volumio Log Rotation Service. Oct 01 10:46:54 volumio systemd[1]: Starting Volumio SSH enabler... Oct 01 10:46:54 volumio systemd[1]: Started Volumio SSH enabler. Oct 01 10:46:54 volumio systemd[1]: Starting Wireless Services... Oct 01 10:46:54 volumio systemd[1]: Starting OpenBSD Secure Shell server... Oct 01 10:46:54 volumio systemd[1]: Starting /etc/rc.local Compatibility... Oct 01 10:46:54 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Oct 01 10:46:54 volumio systemd[1]: Starting Login Service... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: start Winbind daemon... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: triggerhappy hotkey daemon... Oct 01 10:46:54 volumio rc.local[4627]: kernel 3.14.29 Oct 01 10:46:54 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: Start NTP daemon... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: Starts LIRC daemon.... Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio triggerhappy[4638]: Unable to parse trigger line: Oct 01 10:46:54 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Oct 01 10:46:54 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Oct 01 10:46:54 volumio kernel: fb: osd[0] canvas.idx =0x40 Oct 01 10:46:54 volumio kernel: fb: osd[0] canvas.addr=0x7f000000 Oct 01 10:46:54 volumio kernel: fb: osd[0] canvas.width=3840 Oct 01 10:46:54 volumio kernel: fb: osd[0] canvas.height=1440 Oct 01 10:46:54 volumio kernel: fb: osd[0] frame.width=1280 Oct 01 10:46:54 volumio kernel: fb: osd[0] frame.height=720 Oct 01 10:46:54 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 01 10:46:54 volumio systemd[1]: Starting Music Player Daemon... Oct 01 10:46:54 volumio kernel: fb: Frame buffer memory assigned at Oct 01 10:46:54 volumio kernel: fb: 1, phy: 0x000000007fb00000, vir:0xffffff8002a00000, size=1024K Oct 01 10:46:54 volumio systemd[1]: Starting D-Bus System Message Bus... Oct 01 10:46:54 volumio systemd[1]: Started D-Bus System Message Bus. Oct 01 10:46:54 volumio lirc[4689]: No valid /etc/lirc/lircd.conf has been found.. Oct 01 10:46:54 volumio lirc[4689]: Remote control support has been disabled.. Oct 01 10:46:54 volumio lirc[4689]: Reconfigure LIRC or manually replace /etc/lirc/lircd.conf to enable.. Oct 01 10:46:54 volumio kernel: fb: fb1 open, logo index:1 Oct 01 10:46:54 volumio kernel: fb: osd[1] canvas.idx =0x43 Oct 01 10:46:54 volumio kernel: fb: osd[1] canvas.addr=0x7fb00000 Oct 01 10:46:54 volumio kernel: fb: osd[1] canvas.width=128 Oct 01 10:46:54 volumio kernel: fb: osd[1] canvas.height=32 Oct 01 10:46:54 volumio kernel: fb: osd[1] frame.width=32 Oct 01 10:46:54 volumio kernel: fb: osd[1] frame.height=32 Oct 01 10:46:54 volumio loadcpufreq[4654]: Loading cpufreq kernel modules...done (none). Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: version 6.0.5 starting Oct 01 10:46:54 volumio dhcpcd[4727]: version 6.0.5 starting Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: ifplugd 0.28 initializing. Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: Using interface eth0/00:AC:BF:7C:C0:FF with driver (version: March_2013) Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: Using detection mode: SIOCETHTOOL Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: Initialization complete, link beat detected. Oct 01 10:46:54 volumio kernel: Error getting the SSID Oct 01 10:46:54 volumio kernel: vout_serve: vmode set to 720p60hz Oct 01 10:46:54 volumio kernel: fb: current vmode=720p60hz, cmd: 0x50000 Oct 01 10:46:54 volumio kernel: tv_vout: tv_set_current_vmode[919]fps_target_mode=10 Oct 01 10:46:54 volumio kernel: tv_vout: mode is 10,sync_duration_den=1,sync_duration_num=60 Oct 01 10:46:54 volumio kernel: tv_vout: TV mode 720p60hz selected. Oct 01 10:46:54 volumio kernel: vdac_enable: on:0,module_sel:8 Oct 01 10:46:54 volumio kernel: vdac_enable: on:0,module_sel:8 Oct 01 10:46:54 volumio kernel: tv_vout: new mode =720p60hz set ok Oct 01 10:46:54 volumio kernel: vout_serve: vinfo mode is: 720p60hz Oct 01 10:46:54 volumio kernel: vout_serve: new mode 720p60hz set ok Oct 01 10:46:54 volumio kernel: hdmitx: video: get current mode: 720p60hz Oct 01 10:46:54 volumio kernel: hdmitx: update rx hdr info 0 Oct 01 10:46:54 volumio kernel: hdmitx: update physcial size: 16 9 Oct 01 10:46:54 volumio kernel: hdmitx: system: already init VIC = 0 Now VIC = 4 Oct 01 10:46:54 volumio kernel: hdmitx: rx edid only support RGB format Oct 01 10:46:54 volumio kernel: hdmitx: rx no SCDC present indicator Oct 01 10:46:54 volumio avahi-daemon[4701]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Oct 01 10:46:54 volumio avahi-daemon[4701]: Successfully dropped root privileges. Oct 01 10:46:54 volumio avahi-daemon[4701]: avahi-daemon 0.6.31 starting up. Oct 01 10:46:54 volumio kernel: hdmitx: ddc rd8b error 0x54 0x01 Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 01 10:46:54 volumio kernel: hdmitx: ddc rd8b error 0x54 0x01 Oct 01 10:46:54 volumio kernel: hdmirx version is 1.4 or below Oct 01 10:46:54 volumio kernel: hdmitx div40: 0 Oct 01 10:46:54 volumio kernel: hdmitx: system: set mode VIC 4 (cd0,cs0,pm1,vd0,1) Oct 01 10:46:54 volumio kernel: hdmitx: system: set pll Oct 01 10:46:54 volumio kernel: hdmitx: system: param->VIC:4 Oct 01 10:46:54 volumio kernel: hdmitx: ddc w1b error 0x54 0x20 0x00 Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: client: /sbin/ifup: interface eth0 already configured Oct 01 10:46:54 volumio kernel: hdmitx: ddc w1b error 0x54 0x20 0x00 Oct 01 10:46:54 volumio kernel: hdmitx: ddc rd8b error 0x54 0x21 Oct 01 10:46:54 volumio kernel: hdmitx: set clk: VIC = 4 cd = 4 frac_rate = 0 Oct 01 10:46:54 volumio kernel: config HPLL = 2970000 Oct 01 10:46:54 volumio kernel: HPLL: 0xc000027b Oct 01 10:46:54 volumio kernel: config HPLL done Oct 01 10:46:54 volumio kernel: set_hpll_od3_clk_div[437] div = 6 Oct 01 10:46:54 volumio kernel: j = 2 vid_clk_div = 1 Oct 01 10:46:54 volumio kernel: hdmitx: set enc for VIC: 4 Oct 01 10:46:54 volumio kernel: hdmitx_set_hw[4947] set VIC = 4 Oct 01 10:46:54 volumio kernel: HDMITX-DWC addr=0x10004006 rd_data=0x40 Oct 01 10:46:54 volumio kernel: Error: HDMITX-DWC exp_data=0xff mask=0x9f Oct 01 10:46:54 volumio avahi-daemon[4701]: Successfully called chroot(). Oct 01 10:46:54 volumio avahi-daemon[4701]: Successfully dropped remaining capabilities. Oct 01 10:46:54 volumio avahi-daemon[4701]: No service file found in /etc/avahi/services. Oct 01 10:46:54 volumio avahi-daemon[4701]: Network interface enumeration completed. Oct 01 10:46:54 volumio avahi-daemon[4701]: Registering HINFO record with values 'AARCH64'/'LINUX'. Oct 01 10:46:54 volumio avahi-daemon[4701]: Server startup complete. Host name is volumio.local. Local service cookie is 2693026652. Oct 01 10:46:54 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 01 10:46:54 volumio systemd[1]: Starting Permit User Sessions... Oct 01 10:46:54 volumio ntpd[4688]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:59:22 UTC 2016 (1) Oct 01 10:46:54 volumio systemd[1]: Started Restore Sound Card State. Oct 01 10:46:54 volumio sudo[4622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Oct 01 10:46:54 volumio ifplugd(eth0)[4739]: Program executed successfully. Oct 01 10:46:54 volumio ntpd[4779]: proto: precision = 0.500 usec Oct 01 10:46:54 volumio ntp[4650]: Starting NTP server: ntpd. Oct 01 10:46:54 volumio ifplugd[4691]: Network Interface Plugging Daemon...start eth0...done. Oct 01 10:46:54 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Oct 01 10:46:54 volumio systemd[1]: Unit cd.service entered failed state. Oct 01 10:46:54 volumio ntpd[4779]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Oct 01 10:46:54 volumio ntpd[4779]: Listen and drop on 1 v6wildcard :: UDP 123 Oct 01 10:46:54 volumio ntpd[4779]: Listen normally on 2 lo 127.0.0.1 UDP 123 Oct 01 10:46:54 volumio ntpd[4779]: peers refreshed Oct 01 10:46:54 volumio ntpd[4779]: Listening on routing socket on fd #19 for interface updates Oct 01 10:46:54 volumio sudo[4622]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:46:54 volumio dhcpcd[4727]: eth0: soliciting an IPv6 router Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: eth0: soliciting an IPv6 router Oct 01 10:46:54 volumio dhcpcd[4727]: eth0: using static address 192.168.1.70 Oct 01 10:46:54 volumio dhcpcd[4727]: eth0: adding host route to 192.168.1.70 via 127.0.0.1 Oct 01 10:46:54 volumio dhcpcd[4727]: eth0: adding route to 192.168.1.0/24 Oct 01 10:46:54 volumio dhcpcd[4727]: eth0: adding default route via 192.168.1.1 Oct 01 10:46:54 volumio avahi-daemon[4701]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.70. Oct 01 10:46:54 volumio avahi-daemon[4701]: New relevant interface eth0.IPv4 for mDNS. Oct 01 10:46:54 volumio avahi-daemon[4701]: Registering new address record for 192.168.1.70 on eth0.IPv4. Oct 01 10:46:54 volumio sudo[4622]: pam_unix(sudo:session): session closed for user root Oct 01 10:46:54 volumio systemd[1]: Started /etc/rc.local Compatibility. Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: eth0: using static address 192.168.1.70 Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: eth0: adding host route to 192.168.1.70 via 127.0.0.1 Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: eth0: adding route to 192.168.1.0/24 Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: eth0: adding default route via 192.168.1.1 Oct 01 10:46:54 volumio kernel: hdmitx: system: PHY Setting Done Oct 01 10:46:54 volumio kernel: Sink is DVI device Oct 01 10:46:54 volumio kernel: hdmitx: system: packet: can't get vendor data Oct 01 10:46:54 volumio kernel: 720p60hz 60 1 Oct 01 10:46:54 volumio kernel: 720p60hz 60 1 Oct 01 10:46:54 volumio kernel: hdmtix: set audio Oct 01 10:46:54 volumio kernel: hdmitx tx_aud_src = 0 Oct 01 10:46:54 volumio kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 74250 Oct 01 10:46:54 volumio kernel: hdmitx aud_n_para = 6144 Oct 01 10:46:54 volumio kernel: hdmitx set channel status Oct 01 10:46:54 volumio kernel: hdmitx: audio: Audio Type: PCM Oct 01 10:46:54 volumio kernel: fb: current vmode=720p60hz, cmd: 0x10000 Oct 01 10:46:54 volumio kernel: hdmitx: system: irq 80000001 Oct 01 10:46:54 volumio kernel: Start = 0x10000100 End = 0x100001ff Oct 01 10:46:54 volumio kernel: [0x10000103]: 0x00000008 Oct 01 10:46:54 volumio kernel: [0x10000170]: 0x00000010 Oct 01 10:46:54 volumio kernel: [0x10000180]: 0x000000ff Oct 01 10:46:54 volumio kernel: [0x10000181]: 0x000000ff Oct 01 10:46:54 volumio kernel: [0x10000182]: 0x00000003 Oct 01 10:46:54 volumio kernel: [0x10000183]: 0x00000007 Oct 01 10:46:54 volumio kernel: [0x10000184]: 0x0000003f Oct 01 10:46:54 volumio kernel: [0x10000185]: 0x00000002 Oct 01 10:46:54 volumio kernel: [0x10000187]: 0x000000ff Oct 01 10:46:54 volumio kernel: [0x10000188]: 0x00000003 Oct 01 10:46:54 volumio systemd[1]: Started LSB: triggerhappy hotkey daemon. Oct 01 10:46:54 volumio dhcpcd[4727]: forked to background, child pid 4814 Oct 01 10:46:54 volumio dhcpcd[4814]: wlan0: soliciting an IPv6 router Oct 01 10:46:54 volumio dhcpcd[4814]: wlan0: using static address 192.168.1.71 Oct 01 10:46:54 volumio avahi-daemon[4701]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.71. Oct 01 10:46:54 volumio dhcpcd[4699]: dhcpcd[4727]: forked to background, child pid 4814 Oct 01 10:46:54 volumio avahi-daemon[4701]: New relevant interface wlan0.IPv4 for mDNS. Oct 01 10:46:54 volumio avahi-daemon[4701]: Registering new address record for 192.168.1.71 on wlan0.IPv4. Oct 01 10:46:54 volumio systemd[1]: Started LSB: Start NTP daemon. Oct 01 10:46:54 volumio kernel: fb: osd[0] enable: 1 (hdmi.sh) Oct 01 10:46:54 volumio kernel: fb: osd[1] enable: 0 (hdmi.sh) Oct 01 10:46:54 volumio sshd[4817]: Server listening on 0.0.0.0 port 22. Oct 01 10:46:54 volumio sshd[4817]: Server listening on :: port 22. Oct 01 10:46:54 volumio systemd[1]: Started OpenBSD Secure Shell server. Oct 01 10:46:54 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 01 10:46:54 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Oct 01 10:46:54 volumio systemd[1]: Started LSB: Brings up/down network automatically. Oct 01 10:46:54 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Oct 01 10:46:54 volumio systemd[1]: Started LSB: IPv4 DHCP client with IPv4LL support. Oct 01 10:46:54 volumio systemd[1]: Started Permit User Sessions. Oct 01 10:46:54 volumio dbus[4712]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Oct 01 10:46:54 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Oct 01 10:46:55 volumio systemd-logind[4628]: New seat seat0. Oct 01 10:46:55 volumio systemd[1]: Started Login Service. Oct 01 10:46:55 volumio systemd[1]: Starting Disk Manager (legacy version)... Oct 01 10:46:55 volumio systemd[1]: Stopping cd.service... Oct 01 10:46:55 volumio systemd[1]: Starting cd.service... Oct 01 10:46:55 volumio systemd[1]: Started cd.service. Oct 01 10:46:55 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 01 10:46:55 volumio volumio[4831]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Oct 01 10:46:55 volumio systemd[1]: Starting Wait for Plymouth Boot Screen to Quit... Oct 01 10:46:55 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 01 10:46:55 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Oct 01 10:46:55 volumio systemd[1]: Unit cd.service entered failed state. Oct 01 10:46:55 volumio systemd[1]: Started Wait for Plymouth Boot Screen to Quit. Oct 01 10:46:55 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Oct 01 10:46:55 volumio cpufrequtils[4832]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 01 10:46:55 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 01 10:46:55 volumio systemd[1]: Starting Getty on tty1... Oct 01 10:46:55 volumio systemd[1]: Started Getty on tty1. Oct 01 10:46:55 volumio systemd[1]: Starting Serial Getty on ttyS0... Oct 01 10:46:55 volumio systemd[1]: Started Serial Getty on ttyS0. Oct 01 10:46:55 volumio systemd[1]: Starting Login Prompts. Oct 01 10:46:55 volumio systemd[1]: Reached target Login Prompts. Oct 01 10:46:55 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Oct 01 10:46:55 volumio systemd[1]: Stopping cd.service... Oct 01 10:46:55 volumio systemd[1]: Starting cd.service... Oct 01 10:46:55 volumio dbus[4712]: [system] Successfully activated service 'org.freedesktop.UDisks' Oct 01 10:46:55 volumio systemd[1]: Started cd.service. Oct 01 10:46:55 volumio systemd[1]: Started Disk Manager (legacy version). Oct 01 10:46:55 volumio volumio[4852]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Oct 01 10:46:55 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Oct 01 10:46:55 volumio systemd[1]: Unit cd.service entered failed state. Oct 01 10:46:55 volumio haveged[4608]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Oct 01 10:46:55 volumio haveged[4608]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 9/40; sz: 15780/68380 Oct 01 10:46:55 volumio haveged[4608]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00084 Oct 01 10:46:55 volumio haveged[4608]: haveged: fills: 0, generated: 0 Oct 01 10:46:55 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Oct 01 10:46:55 volumio systemd[1]: Stopping cd.service... Oct 01 10:46:55 volumio dbus[4712]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Oct 01 10:46:55 volumio systemd[1]: Starting cd.service... Oct 01 10:46:55 volumio systemd[1]: Started cd.service. Oct 01 10:46:55 volumio volumio[4855]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Oct 01 10:46:55 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Oct 01 10:46:55 volumio systemd[1]: Unit cd.service entered failed state. Oct 01 10:46:55 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Oct 01 10:46:55 volumio volumio-remote-updater[4615]: Error: No active session Oct 01 10:46:55 volumio volumio-remote-updater[4615]: [2024-10-01 10:46:55] [info] asio async_connect error: system:111 (Connection refused) Oct 01 10:46:55 volumio volumio-remote-updater[4615]: [2024-10-01 10:46:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 01 10:46:55 volumio volumio-remote-updater[4615]: [2024-10-01 10:46:55] [error] handle_connect error: Underlying Transport Error Oct 01 10:46:55 volumio polkitd[4857]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 01 10:46:55 volumio dbus[4712]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 01 10:46:55 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Oct 01 10:46:55 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Oct 01 10:46:55 volumio systemd[1]: Stopping cd.service... Oct 01 10:46:55 volumio volumio[4614]: Could not open config: /tmp/upmpdcli.conf Oct 01 10:46:55 volumio systemd[1]: Starting cd.service... Oct 01 10:46:55 volumio systemd[1]: Started cd.service. Oct 01 10:46:55 volumio volumio[4863]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Oct 01 10:46:55 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Oct 01 10:46:55 volumio systemd[1]: Unit cd.service entered failed state. Oct 01 10:46:55 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Oct 01 10:46:55 volumio systemd[1]: Unit upmpdcli.service entered failed state. Oct 01 10:46:55 volumio udisks-glue[4613]: Device file /dev/sda inserted Oct 01 10:46:55 volumio udisks-glue[4613]: Device file /dev/mmcblk1 inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/misc inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/mmcblk0rpmb inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/bootloader inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/tee inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Trying to automount /dev/tee... Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p8): warning: maximal mount count reached, running e2fsck is recommended Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null) Oct 01 10:46:56 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Oct 01 10:46:56 volumio systemd[1]: Stopping cd.service... Oct 01 10:46:56 volumio udisks-glue[4613]: Successfully automounted /dev/tee at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/logo inserted Oct 01 10:46:56 volumio systemd[1]: Starting cd.service... Oct 01 10:46:56 volumio systemd[1]: cd.service start request repeated too quickly, refusing to start. Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/env inserted Oct 01 10:46:56 volumio systemd[1]: Failed to start cd.service. Oct 01 10:46:56 volumio systemd[1]: Unit cd.service entered failed state. Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/boot inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/mmcblk0boot0 inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/mmcblk0boot1 inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/recovery inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/system inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Trying to automount /dev/system... Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: (null) Oct 01 10:46:56 volumio udisks-glue[4613]: Successfully automounted /dev/system at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b_ Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/rsv inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/cache inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Trying to automount /dev/cache... Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p3): warning: maximal mount count reached, running e2fsck is recommended Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Oct 01 10:46:56 volumio udisks-glue[4613]: Successfully automounted /dev/cache at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b__ Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/mmcblk1p3 inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/crypt inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/mmcblk0 inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/reserved inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/data inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Trying to automount /dev/data... Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p13): warning: maximal mount count reached, running e2fsck is recommended Oct 01 10:46:56 volumio kernel: EXT4-fs (mmcblk0p13): mounted filesystem with ordered data mode. Opts: (null) Oct 01 10:46:56 volumio kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Oct 01 10:46:56 volumio udisks-glue[4613]: Successfully automounted /dev/data at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b___ Oct 01 10:46:56 volumio udisks-glue[4613]: Device file /dev/sda1 inserted Oct 01 10:46:56 volumio udisks-glue[4613]: Trying to automount /dev/sda1... Oct 01 10:46:57 volumio ntpd[4779]: Deferring DNS for 0.debian.pool.ntp.org 1 Oct 01 10:46:57 volumio ntpd[4893]: signal_no_reset: signal 17 had flags 4000000 Oct 01 10:46:58 volumio udisks-glue[4613]: Successfully automounted /dev/sda1 at /media/MUSIC Oct 01 10:46:58 volumio ntfs-3g[4897]: Version 2014.2.15AR.2 integrated FUSE 28 Oct 01 10:46:58 volumio ntfs-3g[4897]: Mounted /dev/sda1 (Read-Write, label "MUSIC", NTFS 3.1) Oct 01 10:46:58 volumio ntfs-3g[4897]: Cmdline options: rw,nodev,nosuid,noatime,uid=1000,gid=1000,dmask=0077,fmask=0177,dmask=0000,fmask=0000,uhelper=udisks Oct 01 10:46:58 volumio ntfs-3g[4897]: Mount options: rw,nodev,nosuid,uhelper=udisks,allow_other,nonempty,noatime,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Oct 01 10:46:58 volumio ntfs-3g[4897]: Global ownership and permissions enforced, configuration type 7 Oct 01 10:46:58 volumio udisks-glue[4613]: Device file /dev/tee mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b Oct 01 10:46:58 volumio udisks-glue[4613]: Device file /dev/system mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b_ Oct 01 10:46:58 volumio udisks-glue[4613]: Device file /dev/cache mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b__ Oct 01 10:46:58 volumio udisks-glue[4613]: Device file /dev/data mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b___ Oct 01 10:46:58 volumio udisks-glue[4613]: Device file /dev/sda1 mounted at /media/MUSIC Oct 01 10:46:58 volumio wireless.js[4789]: Cleaning previous... Oct 01 10:46:58 volumio ntpd[4779]: Listen normally on 3 eth0 192.168.1.70 UDP 123 Oct 01 10:46:58 volumio ntpd[4779]: Listen normally on 4 wlan0 192.168.1.71 UDP 123 Oct 01 10:46:58 volumio ntpd[4779]: peers refreshed Oct 01 10:46:58 volumio systemd[1]: Stopped hotspot.service. Oct 01 10:46:58 volumio sudo[4916]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 01 10:46:58 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Oct 01 10:46:58 volumio sudo[4916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:46:58 volumio avahi-daemon[4701]: Withdrawing address record for 192.168.1.71 on wlan0. Oct 01 10:46:58 volumio avahi-daemon[4701]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.71. Oct 01 10:46:58 volumio avahi-daemon[4701]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 01 10:46:58 volumio sudo[4916]: pam_unix(sudo:session): session closed for user root Oct 01 10:46:58 volumio sudo[4925]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 01 10:46:58 volumio sudo[4925]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:46:58 volumio kernel: dhd_stop: Enter ffffffc059172000 Oct 01 10:46:58 volumio kernel: wl_event_handler: was terminated Oct 01 10:46:58 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:119e terminated OK Oct 01 10:46:58 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Oct 01 10:46:58 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Oct 01 10:46:58 volumio kernel: wl_android_wifi_off in 1 Oct 01 10:46:58 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Oct 01 10:46:58 volumio kernel: bcmsdh_oob_intr_unregister: Enter Oct 01 10:46:58 volumio kernel: dhd_txglom_enable: enable 0 Oct 01 10:46:58 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Oct 01 10:46:58 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Oct 01 10:46:58 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Oct 01 10:46:58 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=1, txctl_tmo_fix=0 Oct 01 10:46:58 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Oct 01 10:46:58 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Oct 01 10:46:58 volumio kernel: wifi_platform_set_power = 0 Oct 01 10:46:58 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Oct 01 10:46:58 volumio kernel: wl_android_wifi_off out Oct 01 10:46:59 volumio nmbd[4652]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 01 10:46:59 volumio nmbd[4652]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 01 10:46:59 volumio nmbd[4652]: Unable to setup corepath for nmbd: No such file or directory Oct 01 10:46:59 volumio nmbd[4652]: [2024/10/01 10:46:59, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio nmbd[4652]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 01 10:46:59 volumio nmbd[4652]: [2024/10/01 10:46:59, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio nmbd[4652]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 01 10:46:59 volumio nmbd[4929]: [2024/10/01 10:46:59.078372, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio nmbd[4929]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 01 10:46:59 volumio nmbd[4929]: [2024/10/01 10:46:59.080135, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio nmbd[4929]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 01 10:46:59 volumio nmbd[4652]: [2024/10/01 10:46:59, 0] ../source3/nmbd/nmbd.c:908(main) Oct 01 10:46:59 volumio nmbd[4652]: nmbd version 4.2.14-Debian started. Oct 01 10:46:59 volumio nmbd[4652]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 01 10:46:59 volumio nmbd[4652]: [2024/10/01 10:46:59.078372, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio nmbd[4652]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 01 10:46:59 volumio nmbd[4652]: [2024/10/01 10:46:59.080135, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio nmbd[4652]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 01 10:46:59 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Oct 01 10:46:59 volumio nmbd[4652]: . Oct 01 10:46:59 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Oct 01 10:46:59 volumio winbind[4631]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 01 10:46:59 volumio winbind[4631]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 01 10:46:59 volumio winbind[4631]: Unable to setup corepath for winbindd: No such file or directory Oct 01 10:46:59 volumio winbind[4631]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 01 10:46:59 volumio winbind[4631]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 01 10:46:59 volumio winbind[4631]: Unable to setup corepath for winbindd: No such file or directory Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbind[4631]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbind[4631]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59, 0] ../source3/winbindd/winbindd.c:1549(main) Oct 01 10:46:59 volumio winbind[4631]: winbindd version 4.2.14-Debian started. Oct 01 10:46:59 volumio winbind[4631]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 01 10:46:59 volumio winbind[4631]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 01 10:46:59 volumio winbind[4631]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 01 10:46:59 volumio winbind[4631]: Unable to setup corepath for winbindd: No such file or directory Oct 01 10:46:59 volumio kernel: dhd_stop: Exit Oct 01 10:46:59 volumio sudo[4925]: pam_unix(sudo:session): session closed for user root Oct 01 10:46:59 volumio winbindd[4926]: [2024/10/01 10:46:59.119996, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbindd[4926]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 01 10:46:59 volumio winbindd[4926]: [2024/10/01 10:46:59.120848, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbindd[4926]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 01 10:46:59 volumio nmbd[4930]: [2024/10/01 10:46:59.124615, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 01 10:46:59 volumio nmbd[4930]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 01 10:46:59 volumio systemd[1]: Started LSB: start Winbind daemon. Oct 01 10:46:59 volumio winbindd[4942]: [2024/10/01 10:46:59.154123, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Oct 01 10:46:59 volumio winbindd[4942]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59.119996, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbind[4631]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 01 10:46:59 volumio winbind[4631]: [2024/10/01 10:46:59.120848, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbind[4631]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 01 10:46:59 volumio winbind[4631]: . Oct 01 10:46:59 volumio winbindd[4942]: [2024/10/01 10:46:59.181270, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 01 10:46:59 volumio winbindd[4942]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 01 10:46:59 volumio winbindd[4943]: [2024/10/01 10:46:59.186311, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:46:59 volumio winbindd[4943]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 01 10:46:59 volumio wireless.js[4789]: Stopped aP Oct 01 10:46:59 volumio wireless.js[4789]: Wireless Networking DISABLED, not starting wireless flow Oct 01 10:46:59 volumio systemd[1]: Started Wireless Services. Oct 01 10:46:59 volumio systemd[1]: Starting Volumio Backend Module... Oct 01 10:46:59 volumio systemd[1]: Started Volumio Backend Module. Oct 01 10:46:59 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 01 10:46:59 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 01 10:46:59 volumio ntpd_intres[4893]: DNS 0.debian.pool.ntp.org -> 115.165.161.155 Oct 01 10:46:59 volumio volumio-streaming-daemon[4952]: ############################ Oct 01 10:46:59 volumio volumio-streaming-daemon[4952]: # Volumio Streaming Daemon # Oct 01 10:46:59 volumio volumio-streaming-daemon[4952]: # Running on port 7777 # Oct 01 10:46:59 volumio volumio-streaming-daemon[4952]: ############################ Oct 01 10:46:59 volumio volumio-streaming-daemon[4952]: Environment: production Oct 01 10:47:00 volumio smbd[4931]: Starting SMB/CIFS daemon: smbd[2024/10/01 10:47:00, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4931]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 01 10:47:00 volumio smbd[4931]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 01 10:47:00 volumio smbd[4931]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 01 10:47:00 volumio smbd[4931]: Unable to setup corepath for smbd: No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4931]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00, 0] ../source3/smbd/server.c:1241(main) Oct 01 10:47:00 volumio smbd[4931]: smbd version 4.2.14-Debian started. Oct 01 10:47:00 volumio smbd[4931]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 01 10:47:00 volumio smbd[4962]: [2024/10/01 10:47:00.298009, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4962]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4962]: [2024/10/01 10:47:00.299033, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4962]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4962]: [2024/10/01 10:47:00.299238, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4962]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00.298009, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4931]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00.299033, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4931]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4931]: [2024/10/01 10:47:00.299238, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4931]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio smbd[4931]: . Oct 01 10:47:00 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Oct 01 10:47:00 volumio winbindd[4968]: [2024/10/01 10:47:00.381020, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio winbindd[4968]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 01 10:47:00 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Oct 01 10:47:00 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Oct 01 10:47:00 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Oct 01 10:47:00 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Oct 01 10:47:00 volumio winbindd[4970]: [2024/10/01 10:47:00.475101, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio winbindd[4970]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Oct 01 10:47:00 volumio smbd[4964]: [2024/10/01 10:47:00.510180, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 01 10:47:00 volumio smbd[4964]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 01 10:47:00 volumio smbd[4971]: [2024/10/01 10:47:00.514563, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 01 10:47:00 volumio smbd[4971]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 01 10:47:00 volumio ntpd[4779]: Deleting interface #4 wlan0, 192.168.1.71#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Oct 01 10:47:00 volumio ntpd[4779]: peers refreshed Oct 01 10:47:00 volumio volumio-remote-updater[4615]: [2024-10-01 10:47:00] [info] asio async_connect error: system:111 (Connection refused) Oct 01 10:47:00 volumio volumio-remote-updater[4615]: [2024-10-01 10:47:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 01 10:47:00 volumio volumio-remote-updater[4615]: [2024-10-01 10:47:00] [error] handle_connect error: Underlying Transport Error Oct 01 10:47:02 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:02 volumio volumio[4947]: info: ----- Volumio2 ---- Oct 01 10:47:02 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:02 volumio volumio[4947]: info: ----- System startup ---- Oct 01 10:47:02 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:04 volumio volumio[4947]: info: MYVOLUMIO Environment detected Oct 01 10:47:04 volumio volumio[4947]: info: Plugin folders cleanup Oct 01 10:47:04 volumio volumio[4947]: info: Scanning into folder /volumio/app/plugins/ Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category audio_interface Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category miscellanea Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category music_service Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category plugins.json Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category system_controller Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category user_interface Oct 01 10:47:04 volumio volumio[4947]: info: Scanning into folder /data/plugins/ Oct 01 10:47:04 volumio volumio[4947]: info: Scanning category music_service Oct 01 10:47:04 volumio volumio[4947]: info: Plugin folders cleanup completed Oct 01 10:47:04 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:04 volumio volumio[4947]: info: ----- Core plugins startup ---- Oct 01 10:47:04 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:04 volumio volumio[4947]: info: Loading plugins from folder /volumio/app/plugins/ Oct 01 10:47:04 volumio volumio[4947]: info: Adding plugin upnp to MyMusic Plugins Oct 01 10:47:04 volumio volumio[4947]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 01 10:47:04 volumio volumio[4947]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 01 10:47:04 volumio volumio[4947]: info: Loading plugins from folder /data/plugins/ Oct 01 10:47:04 volumio volumio[4947]: info: Loading plugin "system"... Oct 01 10:47:04 volumio volumio[4947]: info: Loading plugin "appearance"... Oct 01 10:47:05 volumio systemd[1]: Started Music Player Daemon. Oct 01 10:47:05 volumio systemd[1]: Starting Multi-User System. Oct 01 10:47:05 volumio systemd[1]: Reached target Multi-User System. Oct 01 10:47:05 volumio systemd[1]: Starting Graphical Interface. Oct 01 10:47:05 volumio systemd[1]: Reached target Graphical Interface. Oct 01 10:47:05 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 01 10:47:05 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 01 10:47:05 volumio systemd[1]: Startup finished in 16.481s (kernel) + 20.665s (userspace) = 37.146s. Oct 01 10:47:05 volumio udisks-glue[4613]: Updating DB (#1) ... Oct 01 10:47:05 volumio udisks-glue[4613]: volume: n/a repeat: off random: off single: off consume: off Oct 01 10:47:05 volumio udisks-glue[4613]: Updating DB (#1) ... Oct 01 10:47:05 volumio udisks-glue[4613]: volume: n/a repeat: off random: off single: off consume: off Oct 01 10:47:05 volumio udisks-glue[4613]: Updating DB (#1) ... Oct 01 10:47:05 volumio udisks-glue[4613]: volume: n/a repeat: off random: off single: off consume: off Oct 01 10:47:05 volumio udisks-glue[4613]: Updating DB (#1) ... Oct 01 10:47:05 volumio udisks-glue[4613]: volume: n/a repeat: off random: off single: off consume: off Oct 01 10:47:05 volumio udisks-glue[4613]: Updating DB (#1) ... Oct 01 10:47:05 volumio udisks-glue[4613]: volume: n/a repeat: off random: off single: off consume: off Oct 01 10:47:05 volumio systemd[1]: Time has been changed Oct 01 10:47:06 volumio volumio[4947]: info: Loading plugin "network"... Oct 01 10:47:06 volumio volumio[4947]: info: Refreshing Cached IP Addresses Oct 01 10:47:06 volumio sudo[4995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 10:47:06 volumio sudo[4995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:06 volumio volumio[4947]: info: Loading plugin "services"... Oct 01 10:47:06 volumio sudo[4995]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:06 volumio volumio[4947]: info: Loading plugin "alsa_controller"... Oct 01 10:47:06 volumio sudo[4997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 10:47:06 volumio sudo[4997]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:06 volumio sudo[4997]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:07 volumio volumio[4947]: info: Loading plugin "wizard"... Oct 01 10:47:07 volumio volumio[4947]: info: Loading plugin "volumio_command_line_client"... Oct 01 10:47:07 volumio volumio[4947]: info: Loading plugin "upnp"... Oct 01 10:47:07 volumio volumio[4947]: info: [1727779627121] Starting Upmpd Daemon Oct 01 10:47:07 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 10:47:07 volumio volumio[4947]: info: Loading plugin "my_music"... Oct 01 10:47:07 volumio volumio[4947]: info: Loading plugin "mpd"... Oct 01 10:47:07 volumio volumio[4947]: info: Loading plugin "upnp_browser"... Oct 01 10:47:08 volumio volumio-remote-updater[4615]: [2024-10-01 10:47:08] [connect] Successful connection Oct 01 10:47:08 volumio volumio[4947]: info: Loading plugin "networkfs"... Oct 01 10:47:08 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 10:47:08 volumio volumio[4947]: info: Loading plugin "alarm-clock"... Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "airplay_emulation"... Oct 01 10:47:09 volumio volumio[4947]: info: Starting Shairport Sync Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "last_100"... Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "webradio"... Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "i2s_dacs"... Oct 01 10:47:09 volumio volumio[4947]: info: I2S DAC not set, start Auto-detection Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "volumiodiscovery"... Oct 01 10:47:09 volumio volumio[4947]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 01 10:47:09 volumio node[4947]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 01 10:47:09 volumio node[4947]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 10:47:09 volumio node[4947]: *** WARNING *** For more information see Oct 01 10:47:09 volumio node[4947]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 01 10:47:09 volumio node[4947]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 10:47:09 volumio node[4947]: *** WARNING *** For more information see Oct 01 10:47:09 volumio volumio[4947]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 10:47:09 volumio volumio[4947]: *** WARNING *** For more information see Oct 01 10:47:09 volumio volumio[4947]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 01 10:47:09 volumio volumio[4947]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 01 10:47:09 volumio volumio[4947]: *** WARNING *** For more information see Oct 01 10:47:09 volumio volumio[4947]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 01 10:47:09 volumio volumio[4947]: Discovery: StartAdv! undefined Oct 01 10:47:09 volumio volumio[4947]: Discovery: Started advertising... Volumio - undefined Oct 01 10:47:09 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "outputs"... Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "albumart"... Oct 01 10:47:09 volumio volumio[4947]: info: Plugin example_plugin is not enabled Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "inputs"... Oct 01 10:47:09 volumio volumio[4947]: info: Loading plugin "updater_comm"... Oct 01 10:47:10 volumio volumio[4947]: info: Plugin mpdemulation is not enabled Oct 01 10:47:10 volumio volumio[4947]: info: Loading plugin "rest_api"... Oct 01 10:47:10 volumio volumio[4947]: info: Loading plugin "websocket"... Oct 01 10:47:10 volumio volumio[4947]: info: Plugin RoonBridge is not enabled Oct 01 10:47:10 volumio volumio[4947]: info: Loading plugin "podcast"... Oct 01 10:47:10 volumio volumio[4947]: error: !!!! WARNING !!!! Oct 01 10:47:10 volumio volumio[4947]: error: The plugin music_service/podcast failed to load, setting it to stopped. Error: Error: Cannot find module 'kew' Oct 01 10:47:10 volumio volumio[4947]: error: Stack trace: Error: Cannot find module 'kew' Oct 01 10:47:10 volumio volumio[4947]: at Function.Module._resolveFilename (module.js:547:15) Oct 01 10:47:10 volumio volumio[4947]: at Function.Module._load (module.js:474:25) Oct 01 10:47:10 volumio volumio[4947]: at Module.require (module.js:596:17) Oct 01 10:47:10 volumio volumio[4947]: at require (internal/module.js:11:18) Oct 01 10:47:10 volumio volumio[4947]: at Object. (/data/plugins/music_service/podcast/index.js:3:12) Oct 01 10:47:10 volumio volumio[4947]: at Module._compile (module.js:652:30) Oct 01 10:47:10 volumio volumio[4947]: at Object.Module._extensions..js (module.js:663:10) Oct 01 10:47:10 volumio volumio[4947]: at Module.load (module.js:565:32) Oct 01 10:47:10 volumio volumio[4947]: at tryModuleLoad (module.js:505:12) Oct 01 10:47:10 volumio volumio[4947]: at Function.Module._load (module.js:497:3) Oct 01 10:47:10 volumio volumio[4947]: at Module.require (module.js:596:17) Oct 01 10:47:10 volumio volumio[4947]: at require (internal/module.js:11:18) Oct 01 10:47:10 volumio volumio[4947]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:147:29) Oct 01 10:47:10 volumio volumio[4947]: at /volumio/app/pluginmanager.js:245:34 Oct 01 10:47:10 volumio volumio[4947]: at Array.forEach () Oct 01 10:47:10 volumio volumio[4947]: at HashMap. (/volumio/app/pluginmanager.js:244:20) Oct 01 10:47:10 volumio volumio[4947]: error: !!!! WARNING !!!! Oct 01 10:47:10 volumio volumio[4947]: info: Loading plugin "spop"... Oct 01 10:47:10 volumio volumio[4947]: Forking 3 albumart workers Oct 01 10:47:11 volumio volumio[4947]: Starting albumart workers Oct 01 10:47:11 volumio volumio[4947]: Starting albumart workers Oct 01 10:47:11 volumio volumio[4947]: Starting albumart workers Oct 01 10:47:12 volumio volumio[4947]: info: ___________ START PLUGINS ___________ Oct 01 10:47:12 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 10:47:12 volumio volumio[4947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 10:47:12 volumio volumio[4947]: info: [1727779632901] CoreMusicLibrary::Adding element Media Servers Oct 01 10:47:12 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 10:47:12 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 10:47:13 volumio volumio[4947]: info: [1727779633078] CoreMusicLibrary::Adding element Last_100 Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 01 10:47:13 volumio volumio[4947]: info: [1727779633084] CoreMusicLibrary::Adding element Webradio Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 10:47:13 volumio volumio[4947]: [SpotifyConnect] Creating VLS config file Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio sudo[5069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Oct 01 10:47:13 volumio sudo[5069]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:13 volumio volumio[4947]: [SpotifyConnect] Starting metadata listener Oct 01 10:47:13 volumio sudo[5069]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:13 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:13 volumio volumio[4947]: info: ----- MyVolumio plugins startup ---- Oct 01 10:47:13 volumio volumio[4947]: info: ------------------------------------------- Oct 01 10:47:13 volumio volumio[4947]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 01 10:47:13 volumio volumio[4947]: info: Loading i18n strings for locale vi Oct 01 10:47:13 volumio sudo[5073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 01 10:47:13 volumio sudo[5073]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:13 volumio volumio[4947]: Updating browse sources language Oct 01 10:47:13 volumio volumio[4947]: Cannot find translation for sourceMedia Servers Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 10:47:13 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:13 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:13 volumio sudo[5073]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::initPlayerControls Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: BOOT COMPLETED Oct 01 10:47:13 volumio volumio[4947]: [Metrics] CommandRouter: 10s 305.07ms Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::Close All Modals sent Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::Close All Modals sent Oct 01 10:47:13 volumio volumio[4947]: Express server listening on port 3000 Oct 01 10:47:13 volumio volumio[4947]: [Metrics] WebUI: 11s 345.61ms Oct 01 10:47:13 volumio volumio[4947]: Volumio Calling Home Oct 01 10:47:13 volumio volumio[5075]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:13 volumio volumio[5075]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:13 volumio kernel: vollibrespot[5076]: syscall 397 Oct 01 10:47:13 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:13 volumio kernel: CPU: 0 PID: 5076 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:13 volumio kernel: task: ffffffc0591d4000 ti: ffffffc076f00000 task.ti: ffffffc076f00000 Oct 01 10:47:13 volumio kernel: PC is at 0xf71cca42 Oct 01 10:47:13 volumio kernel: LR is at 0xab179c60 Oct 01 10:47:13 volumio kernel: pc : [<00000000f71cca42>] lr : [<00000000ab179c60>] pstate: 60010030 Oct 01 10:47:13 volumio kernel: sp : 00000000fffb2bc8 Oct 01 10:47:13 volumio kernel: x12: 00000000fffb2bd8 Oct 01 10:47:13 volumio kernel: x11: 00000000fffb2d98 x10: 00000000ab1a2bac Oct 01 10:47:13 volumio kernel: x9 : 00000000fffb3630 x8 : 0000000000001000 Oct 01 10:47:13 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:13 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:13 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:13 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:13 volumio kernel: Oct 01 10:47:13 volumio volumio[5075]: Malformed config key: missing field `Authentication` Oct 01 10:47:13 volumio volumio[4947]: info: CoreStateMachine::resetVolumioState Oct 01 10:47:13 volumio volumio[4947]: info: CoreStateMachine::getcurrentVolume Oct 01 10:47:13 volumio volumio[4947]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 10:47:13 volumio volumio[4947]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:14 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::updateTrackBlock Oct 01 10:47:14 volumio volumio[4947]: info: CorePlayQueue::getTrackBlock Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 10:47:14 volumio volumio[4947]: info: Setting Device type: Amlogic Oct 01 10:47:14 volumio volumio[4947]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 01 10:47:14 volumio volumio[4947]: [Metrics] SpotifyConnect: 0s 951.17ms Oct 01 10:47:14 volumio volumio[4947]: info: MPD running with PID4708 Oct 01 10:47:14 volumio volumio[4947]: ,establishing connection Oct 01 10:47:14 volumio sudo[5101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 01 10:47:14 volumio sudo[5101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:14 volumio sudo[5101]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:14 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 01 10:47:14 volumio volumio[4947]: info: Oct 01 10:47:14 volumio volumio[4947]: ---------------------------- Client requests Volume 50 Oct 01 10:47:14 volumio volumio[4947]: info: VolumeController::SetAlsaVolume50 Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:14 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:14 volumio volumio[4947]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 01 10:47:14 volumio volumio[4947]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 10:47:14 volumio volumio[4947]: info: Reloading queue from file Oct 01 10:47:14 volumio volumio[4947]: error: updateQueue error: null Oct 01 10:47:14 volumio volumio[4947]: info: Setting volume on startup at 50 Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::setRepeat false single undefined Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:14 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::setRandom false Oct 01 10:47:14 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:14 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:14 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:15 volumio volumio[4947]: info: Starting Shairport Sync Oct 01 10:47:15 volumio volumio[4947]: info: Starting Shairport Sync Oct 01 10:47:15 volumio sudo[5110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 10:47:15 volumio sudo[5110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:15 volumio volumio[4947]: Discovery: adding 464964ae-4fe2-4ebc-85ed-26c4384c09c5 Oct 01 10:47:15 volumio volumio[4947]: info: mDNS: Found device Volumio Oct 01 10:47:15 volumio sudo[5113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 10:47:15 volumio sudo[5113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:15 volumio volumio[4947]: info: CoreCommandRouter::volumioGetState Oct 01 10:47:15 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 01 10:47:15 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 10:47:15 volumio sudo[5110]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 01 10:47:15 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 01 10:47:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 10:47:15 volumio sudo[5113]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:15 volumio volumio[4947]: info: Shairport-Sync Started Oct 01 10:47:15 volumio volumio[4947]: Error adding Membership: Error: addMembership EINVAL Oct 01 10:47:15 volumio volumio[4947]: info: Shairport-Sync Started Oct 01 10:47:15 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:15 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:15 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:15 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:15 volumio volumio[5127]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:15 volumio volumio[5127]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:15 volumio kernel: vollibrespot[5128]: syscall 397 Oct 01 10:47:15 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:15 volumio kernel: CPU: 3 PID: 5128 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:15 volumio kernel: task: ffffffc05928f000 ti: ffffffc076db4000 task.ti: ffffffc076db4000 Oct 01 10:47:15 volumio kernel: PC is at 0xf6f9aa42 Oct 01 10:47:15 volumio kernel: LR is at 0xab040c60 Oct 01 10:47:15 volumio kernel: pc : [<00000000f6f9aa42>] lr : [<00000000ab040c60>] pstate: 60010030 Oct 01 10:47:15 volumio kernel: sp : 00000000ffba9d78 Oct 01 10:47:15 volumio kernel: x12: 00000000ffba9d88 Oct 01 10:47:15 volumio kernel: x11: 00000000ffba9f48 x10: 00000000ab069bac Oct 01 10:47:15 volumio kernel: x9 : 00000000ffbaa7e0 x8 : 0000000000001000 Oct 01 10:47:15 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:15 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:15 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:15 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:15 volumio kernel: Oct 01 10:47:15 volumio volumio[5127]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:16 volumio volumio[4947]: { Error: ENOSPC: no space left on device, write errno: -28, code: 'ENOSPC', syscall: 'write' } Oct 01 10:47:16 volumio volumio[5127]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:16 volumio volumio[4947]: info: CoreCommandRouter::volumioGetState Oct 01 10:47:16 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:16 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:16 volumio volumio[4947]: info: Oct 01 10:47:16 volumio volumio[4947]: ---------------------------- MPD announces state update: update Oct 01 10:47:16 volumio volumio[4947]: info: ControllerMpd::getState Oct 01 10:47:16 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:16 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:16 volumio volumio[5127]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 01 10:47:16 volumio volumio[5127]: stack backtrace: Oct 01 10:47:17 volumio volumio[4947]: info: Command Router : Notfying DB Updatetrue Oct 01 10:47:17 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:17 volumio volumio[4947]: info: ControllerMpd::pushState Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::servicePushState Oct 01 10:47:17 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:17 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:17 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:17 volumio volumio[4947]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 10:47:17 volumio volumio[4947]: verbose: CURRENT POSITION 0 Oct 01 10:47:17 volumio volumio[4947]: info: CoreStateMachine::syncState stateService stop Oct 01 10:47:17 volumio volumio[4947]: info: CoreStateMachine::syncState currentStatus stop Oct 01 10:47:17 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:17 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:17 volumio volumio[4947]: info: No code Oct 01 10:47:17 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:17 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:17 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:17 volumio volumio[4947]: info: ------------------------------ 633ms Oct 01 10:47:17 volumio sudo[5139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 10:47:17 volumio sudo[5139]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:17 volumio sudo[5141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 10:47:17 volumio sudo[5141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:17 volumio sudo[5139]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:17 volumio sudo[5141]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:17 volumio sudo[5145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 10:47:17 volumio sudo[5145]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:17 volumio sudo[5145]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:17 volumio volumio[5127]: 0: 0xab03ac40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Oct 01 10:47:17 volumio sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 10:47:17 volumio sudo[5147]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 01 10:47:17 volumio volumio[5127]: 1: 0xab03ac40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 01 10:47:17 volumio volumio[5127]: 2: 0xab03ac40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Oct 01 10:47:17 volumio volumio[5127]: 3: 0xab03ac40 - ::fmt::hf1c91d83d30a63b5 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Oct 01 10:47:17 volumio sudo[5147]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:17 volumio volumio[5127]: 4: 0xab060d68 - core::fmt::write::he7b81f119cac9e82 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Oct 01 10:47:17 volumio volumio[5127]: 5: 0xab0345d4 - std::io::Write::write_fmt::hd6a547671801193b Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Oct 01 10:47:17 volumio volumio[5127]: 6: 0xab03c728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Oct 01 10:47:17 volumio volumio[5127]: 7: 0xab03c728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Oct 01 10:47:17 volumio volumio[5127]: 8: 0xab03c728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Oct 01 10:47:17 volumio volumio[5127]: 9: 0xab03c23c - std::panicking::default_hook::h3b931251a9c4b1f6 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Oct 01 10:47:17 volumio volumio[5127]: 10: 0xab03cf44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Oct 01 10:47:17 volumio volumio[5127]: 11: 0xab03cc30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Oct 01 10:47:17 volumio volumio[5127]: 12: 0xab03b1bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Oct 01 10:47:17 volumio volumio[5127]: 13: 0xab03c9ec - rust_begin_unwind Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Oct 01 10:47:17 volumio volumio[5127]: 14: 0xaab1fe2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Oct 01 10:47:17 volumio volumio[5127]: 15: 0xaacfc538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Oct 01 10:47:17 volumio volumio[5127]: 16: 0xaad16af4 - as futures::future::Future>::poll::h38eb93796db62721 Oct 01 10:47:17 volumio volumio[5127]: 17: 0xaacf8ba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Oct 01 10:47:17 volumio volumio[5127]: 18: 0xaad82fc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Oct 01 10:47:17 volumio sudo[5152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 01 10:47:17 volumio sudo[5152]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:17 volumio volumio[5127]: 19: 0xaab48dd8 - ::poll::h06f390c1cf340695 Oct 01 10:47:17 volumio volumio[5127]: 20: 0xaab6018c - futures::task_impl::std::set::h917345edbb2d7b17 Oct 01 10:47:17 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 01 10:47:17 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 01 10:47:17 volumio volumio[5127]: 21: 0xaab81f24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Oct 01 10:47:17 volumio volumio[5127]: 22: 0xaab50924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Oct 01 10:47:17 volumio volumio[5127]: 23: 0xaab81bac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Oct 01 10:47:17 volumio volumio[5127]: 24: 0xaab70604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Oct 01 10:47:17 volumio volumio[5127]: 25: 0xaab3fda4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Oct 01 10:47:17 volumio volumio[5127]: 26: 0xaab4a9a0 - vollibrespot::main::haa33a046348d736f Oct 01 10:47:17 volumio volumio[5127]: 27: 0xaab686fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Oct 01 10:47:17 volumio sudo[5152]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:17 volumio volumio[5127]: 28: 0xaab80278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Oct 01 10:47:17 volumio volumio[4947]: info: Upmpdcli Daemon Started Oct 01 10:47:17 volumio volumio[5127]: 29: 0xab02dbcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Oct 01 10:47:17 volumio volumio[5127]: 30: 0xab02dbcc - std::panicking::try::do_call::h7a5efa020212c0f3 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:17 volumio volumio[5127]: 31: 0xab02dbcc - std::panicking::try::h3145f518af029892 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:17 volumio volumio[5127]: 32: 0xab02dbcc - std::panic::catch_unwind::h876d78503e730edb Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:17 volumio volumio[5127]: 33: 0xab02dbcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Oct 01 10:47:17 volumio volumio[5127]: 34: 0xab02dbcc - std::panicking::try::do_call::h6affa17582fe803e Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:17 volumio volumio[5127]: 35: 0xab02dbcc - std::panicking::try::hf3254f9f909f508b Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:17 volumio volumio[5127]: 36: 0xab02dbcc - std::panic::catch_unwind::h17abded8b6d92414 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:17 volumio volumio[5127]: 37: 0xab02dbcc - std::rt::lang_start_internal::h62925866154f59e6 Oct 01 10:47:17 volumio volumio[5127]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Oct 01 10:47:17 volumio volumio[5127]: 38: 0xaab4b11c - main Oct 01 10:47:17 volumio volumio[5127]: 39: 0xf6f1f632 - __libc_start_main Oct 01 10:47:18 volumio volumio[4947]: info: Checking Spotify Web API Oct 01 10:47:18 volumio volumio[4947]: info: CoreCommandRouter::volumioGetState Oct 01 10:47:18 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:19 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:19 volumio volumio[4947]: info: Oct 01 10:47:19 volumio volumio[4947]: ---------------------------- MPD announces state update: update Oct 01 10:47:19 volumio volumio[4947]: info: ControllerMpd::getState Oct 01 10:47:19 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:19 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:19 volumio volumio[4947]: info: Command Router : Notfying DB Updatetrue Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::Close All Modals sent Oct 01 10:47:19 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:19 volumio volumio[4947]: info: ControllerMpd::pushState Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::servicePushState Oct 01 10:47:19 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:19 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:19 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:19 volumio volumio[4947]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 10:47:19 volumio volumio[4947]: verbose: CURRENT POSITION 0 Oct 01 10:47:19 volumio volumio[4947]: info: CoreStateMachine::syncState stateService stop Oct 01 10:47:19 volumio volumio[4947]: info: CoreStateMachine::syncState currentStatus stop Oct 01 10:47:19 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:19 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:19 volumio volumio[4947]: info: No code Oct 01 10:47:19 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:19 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:19 volumio volumio[4947]: info: ------------------------------ 102ms Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 01 10:47:19 volumio volumio[4947]: info: Listing playlists Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 01 10:47:19 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 01 10:47:19 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:19 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:19 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:19 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:19 volumio volumio[5181]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:19 volumio volumio[5181]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:19 volumio kernel: vollibrespot[5182]: syscall 397 Oct 01 10:47:19 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:19 volumio kernel: CPU: 3 PID: 5182 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:19 volumio kernel: task: ffffffc05c17d000 ti: ffffffc077048000 task.ti: ffffffc077048000 Oct 01 10:47:19 volumio kernel: PC is at 0xf7529a42 Oct 01 10:47:19 volumio kernel: LR is at 0xab0ccc60 Oct 01 10:47:19 volumio kernel: pc : [<00000000f7529a42>] lr : [<00000000ab0ccc60>] pstate: 60010030 Oct 01 10:47:19 volumio kernel: sp : 00000000ff810a38 Oct 01 10:47:19 volumio kernel: x12: 00000000ff810a48 Oct 01 10:47:19 volumio kernel: x11: 00000000ff810c08 x10: 00000000ab0f5bac Oct 01 10:47:19 volumio kernel: x9 : 00000000ff8114a0 x8 : 0000000000001000 Oct 01 10:47:19 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:19 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:19 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:19 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:19 volumio kernel: Oct 01 10:47:19 volumio volumio[5181]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:19 volumio volumio[5181]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:20 volumio volumio[5181]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 01 10:47:20 volumio volumio[5181]: stack backtrace: Oct 01 10:47:20 volumio volumio[5181]: 0: 0xab0c6c40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 01 10:47:20 volumio volumio[5181]: 1: 0xab0c6c40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 01 10:47:20 volumio volumio[5181]: 2: 0xab0c6c40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Oct 01 10:47:20 volumio volumio[5181]: 3: 0xab0c6c40 - ::fmt::hf1c91d83d30a63b5 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Oct 01 10:47:20 volumio volumio[5181]: 4: 0xab0ecd68 - core::fmt::write::he7b81f119cac9e82 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Oct 01 10:47:20 volumio volumio[5181]: 5: 0xab0c05d4 - std::io::Write::write_fmt::hd6a547671801193b Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Oct 01 10:47:20 volumio volumio[5181]: 6: 0xab0c8728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Oct 01 10:47:20 volumio volumio[5181]: 7: 0xab0c8728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Oct 01 10:47:20 volumio volumio[5181]: 8: 0xab0c8728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Oct 01 10:47:20 volumio volumio[5181]: 9: 0xab0c823c - std::panicking::default_hook::h3b931251a9c4b1f6 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Oct 01 10:47:20 volumio volumio[5181]: 10: 0xab0c8f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Oct 01 10:47:20 volumio volumio[5181]: 11: 0xab0c8c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Oct 01 10:47:20 volumio volumio[5181]: 12: 0xab0c71bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Oct 01 10:47:20 volumio volumio[5181]: 13: 0xab0c89ec - rust_begin_unwind Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Oct 01 10:47:20 volumio volumio[5181]: 14: 0xaababe2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Oct 01 10:47:20 volumio volumio[5181]: 15: 0xaad88538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Oct 01 10:47:20 volumio volumio[5181]: 16: 0xaada2af4 - as futures::future::Future>::poll::h38eb93796db62721 Oct 01 10:47:20 volumio volumio[5181]: 17: 0xaad84ba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Oct 01 10:47:20 volumio volumio[5181]: 18: 0xaae0efc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Oct 01 10:47:20 volumio volumio[5181]: 19: 0xaabd4dd8 - ::poll::h06f390c1cf340695 Oct 01 10:47:20 volumio volumio[5181]: 20: 0xaabec18c - futures::task_impl::std::set::h917345edbb2d7b17 Oct 01 10:47:20 volumio volumio[5181]: 21: 0xaac0df24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Oct 01 10:47:20 volumio volumio[5181]: 22: 0xaabdc924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Oct 01 10:47:20 volumio volumio[5181]: 23: 0xaac0dbac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Oct 01 10:47:20 volumio volumio[5181]: 24: 0xaabfc604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Oct 01 10:47:20 volumio volumio[5181]: 25: 0xaabcbda4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Oct 01 10:47:20 volumio volumio[5181]: 26: 0xaabd69a0 - vollibrespot::main::haa33a046348d736f Oct 01 10:47:20 volumio volumio[5181]: 27: 0xaabf46fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Oct 01 10:47:20 volumio volumio[5181]: 28: 0xaac0c278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Oct 01 10:47:20 volumio volumio[5181]: 29: 0xab0b9bcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Oct 01 10:47:20 volumio volumio[5181]: 30: 0xab0b9bcc - std::panicking::try::do_call::h7a5efa020212c0f3 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:20 volumio volumio[5181]: 31: 0xab0b9bcc - std::panicking::try::h3145f518af029892 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:20 volumio volumio[5181]: 32: 0xab0b9bcc - std::panic::catch_unwind::h876d78503e730edb Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:20 volumio volumio[5181]: 33: 0xab0b9bcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Oct 01 10:47:20 volumio volumio[5181]: 34: 0xab0b9bcc - std::panicking::try::do_call::h6affa17582fe803e Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:20 volumio volumio[5181]: 35: 0xab0b9bcc - std::panicking::try::hf3254f9f909f508b Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:20 volumio volumio[5181]: 36: 0xab0b9bcc - std::panic::catch_unwind::h17abded8b6d92414 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:20 volumio volumio[5181]: 37: 0xab0b9bcc - std::rt::lang_start_internal::h62925866154f59e6 Oct 01 10:47:20 volumio volumio[5181]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Oct 01 10:47:20 volumio volumio[5181]: 38: 0xaabd711c - main Oct 01 10:47:20 volumio volumio[5181]: 39: 0xf74ae632 - __libc_start_main Oct 01 10:47:21 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:21 volumio volumio[4947]: info: Oct 01 10:47:21 volumio volumio[4947]: ---------------------------- MPD announces state update: update Oct 01 10:47:21 volumio volumio[4947]: info: ControllerMpd::getState Oct 01 10:47:21 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:21 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:21 volumio volumio[4947]: info: Command Router : Notfying DB Updatetrue Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::Close All Modals sent Oct 01 10:47:21 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:21 volumio volumio[4947]: info: ControllerMpd::pushState Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::servicePushState Oct 01 10:47:21 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:21 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:21 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:21 volumio volumio[4947]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 10:47:21 volumio volumio[4947]: verbose: CURRENT POSITION 0 Oct 01 10:47:21 volumio volumio[4947]: info: CoreStateMachine::syncState stateService stop Oct 01 10:47:21 volumio volumio[4947]: info: CoreStateMachine::syncState currentStatus stop Oct 01 10:47:21 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:21 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:21 volumio volumio[4947]: info: No code Oct 01 10:47:21 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:21 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:21 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:21 volumio volumio[4947]: info: ------------------------------ 110ms Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 01 10:47:21 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 01 10:47:22 volumio volumio[4947]: info: Adding plugin bluetooth to MyMusic Plugins Oct 01 10:47:22 volumio volumio[4947]: info: Adding plugin cd_controller to MyMusic Plugins Oct 01 10:47:22 volumio volumio[4947]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 01 10:47:22 volumio volumio[4947]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 01 10:47:22 volumio nmbd[4930]: [2024/10/01 10:47:22.374424, 0] ../source3/nmbd/nmbd_become_lmb.c:397(become_local_master_stage2) Oct 01 10:47:22 volumio nmbd[4930]: ***** Oct 01 10:47:22 volumio nmbd[4930]: Oct 01 10:47:22 volumio nmbd[4930]: Samba name server VOLUMIO is now a local master browser for workgroup WORKGROUP on subnet 192.168.1.70 Oct 01 10:47:22 volumio nmbd[4930]: Oct 01 10:47:22 volumio nmbd[4930]: ***** Oct 01 10:47:22 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:22 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:22 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:22 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:22 volumio volumio[5200]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:22 volumio volumio[5200]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:22 volumio kernel: vollibrespot[5201]: syscall 397 Oct 01 10:47:22 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:22 volumio kernel: CPU: 0 PID: 5201 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:22 volumio kernel: task: ffffffc05c17d000 ti: ffffffc059a7c000 task.ti: ffffffc059a7c000 Oct 01 10:47:22 volumio kernel: PC is at 0xf75a1a42 Oct 01 10:47:22 volumio kernel: LR is at 0xab571c60 Oct 01 10:47:22 volumio kernel: pc : [<00000000f75a1a42>] lr : [<00000000ab571c60>] pstate: 60010030 Oct 01 10:47:22 volumio kernel: sp : 00000000ffa5ebb8 Oct 01 10:47:22 volumio kernel: x12: 00000000ffa5ebc8 Oct 01 10:47:22 volumio kernel: x11: 00000000ffa5ed88 x10: 00000000ab59abac Oct 01 10:47:22 volumio kernel: x9 : 00000000ffa5f620 x8 : 0000000000001000 Oct 01 10:47:22 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:22 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:22 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:22 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:22 volumio volumio[5200]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:22 volumio kernel: Oct 01 10:47:22 volumio volumio[5200]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:23 volumio volumio[5200]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 01 10:47:23 volumio volumio[5200]: stack backtrace: Oct 01 10:47:23 volumio volumio[5200]: 0: 0xab56bc40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 01 10:47:23 volumio volumio[5200]: 1: 0xab56bc40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 01 10:47:23 volumio volumio[5200]: 2: 0xab56bc40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Oct 01 10:47:23 volumio volumio[5200]: 3: 0xab56bc40 - ::fmt::hf1c91d83d30a63b5 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Oct 01 10:47:23 volumio volumio[5200]: 4: 0xab591d68 - core::fmt::write::he7b81f119cac9e82 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Oct 01 10:47:23 volumio volumio[5200]: 5: 0xab5655d4 - std::io::Write::write_fmt::hd6a547671801193b Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Oct 01 10:47:23 volumio volumio[5200]: 6: 0xab56d728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Oct 01 10:47:23 volumio volumio[5200]: 7: 0xab56d728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Oct 01 10:47:23 volumio volumio[5200]: 8: 0xab56d728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Oct 01 10:47:23 volumio volumio[5200]: 9: 0xab56d23c - std::panicking::default_hook::h3b931251a9c4b1f6 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Oct 01 10:47:23 volumio volumio[5200]: 10: 0xab56df44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Oct 01 10:47:23 volumio volumio[5200]: 11: 0xab56dc30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Oct 01 10:47:23 volumio volumio[5200]: 12: 0xab56c1bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Oct 01 10:47:23 volumio volumio[5200]: 13: 0xab56d9ec - rust_begin_unwind Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Oct 01 10:47:23 volumio volumio[5200]: 14: 0xab050e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Oct 01 10:47:23 volumio volumio[5200]: 15: 0xab22d538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Oct 01 10:47:23 volumio volumio[5200]: 16: 0xab247af4 - as futures::future::Future>::poll::h38eb93796db62721 Oct 01 10:47:23 volumio volumio[5200]: 17: 0xab229ba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Oct 01 10:47:23 volumio volumio[5200]: 18: 0xab2b3fc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Oct 01 10:47:23 volumio volumio[5200]: 19: 0xab079dd8 - ::poll::h06f390c1cf340695 Oct 01 10:47:23 volumio volumio[5200]: 20: 0xab09118c - futures::task_impl::std::set::h917345edbb2d7b17 Oct 01 10:47:23 volumio volumio[5200]: 21: 0xab0b2f24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Oct 01 10:47:23 volumio volumio[5200]: 22: 0xab081924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Oct 01 10:47:23 volumio volumio[5200]: 23: 0xab0b2bac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Oct 01 10:47:23 volumio volumio[5200]: 24: 0xab0a1604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Oct 01 10:47:23 volumio volumio[5200]: 25: 0xab070da4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Oct 01 10:47:23 volumio volumio[5200]: 26: 0xab07b9a0 - vollibrespot::main::haa33a046348d736f Oct 01 10:47:23 volumio volumio[5200]: 27: 0xab0996fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Oct 01 10:47:23 volumio volumio[5200]: 28: 0xab0b1278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Oct 01 10:47:23 volumio volumio[5200]: 29: 0xab55ebcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Oct 01 10:47:23 volumio volumio[5200]: 30: 0xab55ebcc - std::panicking::try::do_call::h7a5efa020212c0f3 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:23 volumio volumio[5200]: 31: 0xab55ebcc - std::panicking::try::h3145f518af029892 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:23 volumio volumio[5200]: 32: 0xab55ebcc - std::panic::catch_unwind::h876d78503e730edb Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:23 volumio volumio[5200]: 33: 0xab55ebcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Oct 01 10:47:23 volumio volumio[5200]: 34: 0xab55ebcc - std::panicking::try::do_call::h6affa17582fe803e Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:23 volumio volumio[5200]: 35: 0xab55ebcc - std::panicking::try::hf3254f9f909f508b Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:23 volumio volumio[5200]: 36: 0xab55ebcc - std::panic::catch_unwind::h17abded8b6d92414 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:23 volumio volumio[5200]: 37: 0xab55ebcc - std::rt::lang_start_internal::h62925866154f59e6 Oct 01 10:47:23 volumio volumio[5200]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Oct 01 10:47:23 volumio volumio[5200]: 38: 0xab07c11c - main Oct 01 10:47:23 volumio volumio[5200]: 39: 0xf7526632 - __libc_start_main Oct 01 10:47:23 volumio volumio-remote-updater[4615]: [2024-10-01 10:47:23] [connect] Successful connection Oct 01 10:47:25 volumio volumio[4947]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 01 10:47:25 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:25 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:25 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:25 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:25 volumio volumio[5209]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:25 volumio volumio[5209]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:25 volumio kernel: vollibrespot[5210]: syscall 397 Oct 01 10:47:25 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:25 volumio kernel: CPU: 1 PID: 5210 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:25 volumio kernel: task: ffffffc05cc61000 ti: ffffffc059ac8000 task.ti: ffffffc059ac8000 Oct 01 10:47:25 volumio kernel: PC is at 0xf6e2aa42 Oct 01 10:47:25 volumio kernel: LR is at 0xab4a2c60 Oct 01 10:47:25 volumio kernel: pc : [<00000000f6e2aa42>] lr : [<00000000ab4a2c60>] pstate: 60010030 Oct 01 10:47:25 volumio kernel: sp : 00000000ff9146f8 Oct 01 10:47:25 volumio kernel: x12: 00000000ff914708 Oct 01 10:47:25 volumio kernel: x11: 00000000ff9148c8 x10: 00000000ab4cbbac Oct 01 10:47:25 volumio kernel: x9 : 00000000ff915160 x8 : 0000000000001000 Oct 01 10:47:25 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:25 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:25 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:25 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:25 volumio volumio[5209]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:25 volumio kernel: Oct 01 10:47:25 volumio volumio[5209]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:26 volumio volumio[5209]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 01 10:47:26 volumio volumio[5209]: stack backtrace: Oct 01 10:47:26 volumio volumio[5209]: 0: 0xab49cc40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 01 10:47:26 volumio volumio[5209]: 1: 0xab49cc40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 01 10:47:26 volumio volumio[5209]: 2: 0xab49cc40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Oct 01 10:47:26 volumio volumio[5209]: 3: 0xab49cc40 - ::fmt::hf1c91d83d30a63b5 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Oct 01 10:47:26 volumio volumio[5209]: 4: 0xab4c2d68 - core::fmt::write::he7b81f119cac9e82 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Oct 01 10:47:26 volumio volumio[5209]: 5: 0xab4965d4 - std::io::Write::write_fmt::hd6a547671801193b Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Oct 01 10:47:26 volumio volumio[5209]: 6: 0xab49e728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Oct 01 10:47:26 volumio volumio[5209]: 7: 0xab49e728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Oct 01 10:47:26 volumio volumio[5209]: 8: 0xab49e728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Oct 01 10:47:26 volumio volumio[5209]: 9: 0xab49e23c - std::panicking::default_hook::h3b931251a9c4b1f6 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Oct 01 10:47:26 volumio volumio[5209]: 10: 0xab49ef44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Oct 01 10:47:26 volumio volumio[5209]: 11: 0xab49ec30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Oct 01 10:47:26 volumio volumio[5209]: 12: 0xab49d1bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Oct 01 10:47:26 volumio volumio[5209]: 13: 0xab49e9ec - rust_begin_unwind Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Oct 01 10:47:26 volumio volumio[5209]: 14: 0xaaf81e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Oct 01 10:47:26 volumio volumio[5209]: 15: 0xab15e538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Oct 01 10:47:26 volumio volumio[5209]: 16: 0xab178af4 - as futures::future::Future>::poll::h38eb93796db62721 Oct 01 10:47:26 volumio volumio[5209]: 17: 0xab15aba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Oct 01 10:47:26 volumio volumio[5209]: 18: 0xab1e4fc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Oct 01 10:47:26 volumio volumio[5209]: 19: 0xaafaadd8 - ::poll::h06f390c1cf340695 Oct 01 10:47:26 volumio volumio[5209]: 20: 0xaafc218c - futures::task_impl::std::set::h917345edbb2d7b17 Oct 01 10:47:26 volumio volumio[5209]: 21: 0xaafe3f24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Oct 01 10:47:26 volumio volumio[5209]: 22: 0xaafb2924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Oct 01 10:47:26 volumio volumio[5209]: 23: 0xaafe3bac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Oct 01 10:47:26 volumio volumio[5209]: 24: 0xaafd2604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Oct 01 10:47:26 volumio volumio[5209]: 25: 0xaafa1da4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Oct 01 10:47:26 volumio volumio[5209]: 26: 0xaafac9a0 - vollibrespot::main::haa33a046348d736f Oct 01 10:47:26 volumio volumio[5209]: 27: 0xaafca6fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Oct 01 10:47:26 volumio volumio[5209]: 28: 0xaafe2278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Oct 01 10:47:26 volumio volumio[5209]: 29: 0xab48fbcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Oct 01 10:47:26 volumio volumio[5209]: 30: 0xab48fbcc - std::panicking::try::do_call::h7a5efa020212c0f3 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:26 volumio volumio[5209]: 31: 0xab48fbcc - std::panicking::try::h3145f518af029892 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:26 volumio volumio[5209]: 32: 0xab48fbcc - std::panic::catch_unwind::h876d78503e730edb Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:26 volumio volumio[5209]: 33: 0xab48fbcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Oct 01 10:47:26 volumio volumio[5209]: 34: 0xab48fbcc - std::panicking::try::do_call::h6affa17582fe803e Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:26 volumio volumio[5209]: 35: 0xab48fbcc - std::panicking::try::hf3254f9f909f508b Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:26 volumio volumio[5209]: 36: 0xab48fbcc - std::panic::catch_unwind::h17abded8b6d92414 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:26 volumio volumio[5209]: 37: 0xab48fbcc - std::rt::lang_start_internal::h62925866154f59e6 Oct 01 10:47:26 volumio volumio[5209]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Oct 01 10:47:26 volumio volumio[5209]: 38: 0xaafad11c - main Oct 01 10:47:26 volumio volumio[5209]: 39: 0xf6daf632 - __libc_start_main Oct 01 10:47:26 volumio volumio[4947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 01 10:47:26 volumio volumio[4947]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 01 10:47:26 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 10:47:26 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 10:47:26 volumio volumio[4947]: info: Starting MyVolumio Remote Streaming Endpoints Oct 01 10:47:26 volumio volumio[4947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 01 10:47:26 volumio volumio[4947]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 01 10:47:26 volumio volumio[4947]: info: Streaming services startup Oct 01 10:47:26 volumio volumio[4947]: info: Starting Streaming Daemon Oct 01 10:47:26 volumio sudo[5219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 01 10:47:26 volumio sudo[5219]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 10:47:26 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 01 10:47:26 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 01 10:47:26 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 01 10:47:26 volumio sudo[5219]: pam_unix(sudo:session): session closed for user root Oct 01 10:47:27 volumio volumio-streaming-daemon[5226]: ############################ Oct 01 10:47:27 volumio volumio-streaming-daemon[5226]: # Volumio Streaming Daemon # Oct 01 10:47:27 volumio volumio-streaming-daemon[5226]: # Running on port 7777 # Oct 01 10:47:27 volumio volumio-streaming-daemon[5226]: ############################ Oct 01 10:47:27 volumio volumio[4947]: info: MyVolumio not started Oct 01 10:47:27 volumio volumio-streaming-daemon[5226]: Environment: production Oct 01 10:47:27 volumio volumio[4947]: info: Initializing device activation check Oct 01 10:47:27 volumio volumio[4947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 01 10:47:27 volumio volumio[4947]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=c81d1a704857350dd1987a6d50fa6428" http://updates.volumio.org:7070/downloader-v1/track-device Oct 01 10:47:27 volumio volumio[4947]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 01 10:47:27 volumio volumio[4947]: Dload Upload Total Spent Left Speed Oct 01 10:47:27 volumio volumio[4947]: [235B blob data] Oct 01 10:47:27 volumio volumio[4947]: retrying in 5 seconds, trial 0 Oct 01 10:47:27 volumio volumio[4947]: Volumio Calling Home Oct 01 10:47:27 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:27 volumio volumio[4947]: info: Oct 01 10:47:27 volumio volumio[4947]: ---------------------------- MPD announces state update: update Oct 01 10:47:27 volumio volumio[4947]: info: ControllerMpd::getState Oct 01 10:47:27 volumio volumio[4947]: verbose: ControllerMpd::sendMpdCommand status Oct 01 10:47:27 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:27 volumio volumio[4947]: info: Command Router : Notfying DB Updatefalse Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::Close All Modals sent Oct 01 10:47:27 volumio volumio[4947]: verbose: ControllerMpd::parseState Oct 01 10:47:27 volumio volumio[4947]: info: ControllerMpd::pushState Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::servicePushState Oct 01 10:47:27 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:27 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:27 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:27 volumio volumio[4947]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 10:47:27 volumio volumio[4947]: verbose: CURRENT POSITION 0 Oct 01 10:47:27 volumio volumio[4947]: info: CoreStateMachine::syncState stateService stop Oct 01 10:47:27 volumio volumio[4947]: info: CoreStateMachine::syncState currentStatus stop Oct 01 10:47:27 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:27 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:27 volumio volumio[4947]: info: No code Oct 01 10:47:27 volumio volumio[4947]: info: CoreStateMachine::pushState Oct 01 10:47:27 volumio volumio[4947]: info: CorePlayQueue::getTrack 0 Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::volumioPushState Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 10:47:27 volumio volumio[4947]: info: ------------------------------ 95ms Oct 01 10:47:27 volumio volumio-remote-updater[4615]: [2024-10-01 10:47:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1727779643 101 Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::volumioGetVisibleSources Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 10:47:27 volumio volumio[4947]: info: CoreCommandRouter::volumioGetQueue Oct 01 10:47:27 volumio volumio[4947]: info: CoreStateMachine::getQueue Oct 01 10:47:27 volumio volumio[4947]: info: CorePlayQueue::getQueue Oct 01 10:47:28 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:28 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:28 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:28 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:28 volumio volumio[5242]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:28 volumio volumio[5242]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:28 volumio kernel: vollibrespot[5243]: syscall 397 Oct 01 10:47:28 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:28 volumio kernel: CPU: 2 PID: 5243 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:28 volumio kernel: task: ffffffc059f13000 ti: ffffffc059b00000 task.ti: ffffffc059b00000 Oct 01 10:47:28 volumio kernel: PC is at 0xf6f7ca42 Oct 01 10:47:28 volumio kernel: LR is at 0xab2a5c60 Oct 01 10:47:28 volumio kernel: pc : [<00000000f6f7ca42>] lr : [<00000000ab2a5c60>] pstate: 60010030 Oct 01 10:47:28 volumio kernel: sp : 00000000fffedf58 Oct 01 10:47:28 volumio kernel: x12: 00000000fffedf68 Oct 01 10:47:28 volumio kernel: x11: 00000000fffee128 x10: 00000000ab2cebac Oct 01 10:47:28 volumio kernel: x9 : 00000000fffee9c0 x8 : 0000000000001000 Oct 01 10:47:28 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:28 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:28 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:28 volumio volumio[5242]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:28 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:28 volumio kernel: Oct 01 10:47:28 volumio volumio[5242]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:29 volumio volumio[5242]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 01 10:47:29 volumio volumio[5242]: stack backtrace: Oct 01 10:47:29 volumio volumio[5242]: 0: 0xab29fc40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 01 10:47:29 volumio volumio[5242]: 1: 0xab29fc40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 01 10:47:29 volumio volumio[5242]: 2: 0xab29fc40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Oct 01 10:47:29 volumio volumio[5242]: 3: 0xab29fc40 - ::fmt::hf1c91d83d30a63b5 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Oct 01 10:47:29 volumio volumio[5242]: 4: 0xab2c5d68 - core::fmt::write::he7b81f119cac9e82 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Oct 01 10:47:29 volumio volumio[5242]: 5: 0xab2995d4 - std::io::Write::write_fmt::hd6a547671801193b Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Oct 01 10:47:29 volumio volumio[5242]: 6: 0xab2a1728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Oct 01 10:47:29 volumio volumio[5242]: 7: 0xab2a1728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Oct 01 10:47:29 volumio volumio[5242]: 8: 0xab2a1728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Oct 01 10:47:29 volumio volumio[5242]: 9: 0xab2a123c - std::panicking::default_hook::h3b931251a9c4b1f6 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Oct 01 10:47:29 volumio volumio[5242]: 10: 0xab2a1f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Oct 01 10:47:29 volumio volumio[5242]: 11: 0xab2a1c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Oct 01 10:47:29 volumio volumio[5242]: 12: 0xab2a01bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Oct 01 10:47:29 volumio volumio[5242]: 13: 0xab2a19ec - rust_begin_unwind Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Oct 01 10:47:29 volumio volumio[5242]: 14: 0xaad84e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Oct 01 10:47:29 volumio volumio[5242]: 15: 0xaaf61538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Oct 01 10:47:29 volumio volumio[5242]: 16: 0xaaf7baf4 - as futures::future::Future>::poll::h38eb93796db62721 Oct 01 10:47:29 volumio volumio[5242]: 17: 0xaaf5dba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Oct 01 10:47:29 volumio volumio[5242]: 18: 0xaafe7fc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Oct 01 10:47:29 volumio volumio[5242]: 19: 0xaadaddd8 - ::poll::h06f390c1cf340695 Oct 01 10:47:29 volumio volumio[5242]: 20: 0xaadc518c - futures::task_impl::std::set::h917345edbb2d7b17 Oct 01 10:47:29 volumio volumio[5242]: 21: 0xaade6f24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Oct 01 10:47:29 volumio volumio[5242]: 22: 0xaadb5924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Oct 01 10:47:29 volumio volumio[5242]: 23: 0xaade6bac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Oct 01 10:47:29 volumio volumio[5242]: 24: 0xaadd5604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Oct 01 10:47:29 volumio volumio[5242]: 25: 0xaada4da4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Oct 01 10:47:29 volumio volumio[5242]: 26: 0xaadaf9a0 - vollibrespot::main::haa33a046348d736f Oct 01 10:47:29 volumio volumio[5242]: 27: 0xaadcd6fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Oct 01 10:47:29 volumio volumio[5242]: 28: 0xaade5278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Oct 01 10:47:29 volumio volumio[5242]: 29: 0xab292bcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Oct 01 10:47:29 volumio volumio[5242]: 30: 0xab292bcc - std::panicking::try::do_call::h7a5efa020212c0f3 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:29 volumio volumio[5242]: 31: 0xab292bcc - std::panicking::try::h3145f518af029892 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:29 volumio volumio[5242]: 32: 0xab292bcc - std::panic::catch_unwind::h876d78503e730edb Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:29 volumio volumio[5242]: 33: 0xab292bcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Oct 01 10:47:29 volumio volumio[5242]: 34: 0xab292bcc - std::panicking::try::do_call::h6affa17582fe803e Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:29 volumio volumio[5242]: 35: 0xab292bcc - std::panicking::try::hf3254f9f909f508b Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:29 volumio volumio[5242]: 36: 0xab292bcc - std::panic::catch_unwind::h17abded8b6d92414 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:29 volumio volumio[5242]: 37: 0xab292bcc - std::rt::lang_start_internal::h62925866154f59e6 Oct 01 10:47:29 volumio volumio[5242]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Oct 01 10:47:29 volumio volumio[5242]: 38: 0xaadb011c - main Oct 01 10:47:29 volumio volumio[5242]: 39: 0xf6f01632 - __libc_start_main Oct 01 10:47:29 volumio volumio-streaming-daemon[5226]: Environment: production Oct 01 10:47:29 volumio volumio[4947]: info: Fetching Streaming Services browse cache Oct 01 10:47:31 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:31 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:31 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:31 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:31 volumio volumio[5259]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:31 volumio volumio[5259]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:31 volumio kernel: vollibrespot[5260]: syscall 397 Oct 01 10:47:31 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:31 volumio kernel: CPU: 2 PID: 5260 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:31 volumio kernel: task: ffffffc059f13000 ti: ffffffc076c58000 task.ti: ffffffc076c58000 Oct 01 10:47:31 volumio kernel: PC is at 0xf6ec5a42 Oct 01 10:47:31 volumio kernel: LR is at 0xab2a6c60 Oct 01 10:47:31 volumio kernel: pc : [<00000000f6ec5a42>] lr : [<00000000ab2a6c60>] pstate: 60010030 Oct 01 10:47:31 volumio kernel: sp : 00000000ffbe97a8 Oct 01 10:47:31 volumio kernel: x12: 00000000ffbe97b8 Oct 01 10:47:31 volumio kernel: x11: 00000000ffbe9978 x10: 00000000ab2cfbac Oct 01 10:47:31 volumio kernel: x9 : 00000000ffbea210 x8 : 0000000000001000 Oct 01 10:47:31 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:31 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:31 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:31 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:31 volumio kernel: Oct 01 10:47:31 volumio volumio[5259]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:31 volumio volumio[5259]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:32 volumio volumio[5259]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 01 10:47:32 volumio volumio[5259]: stack backtrace: Oct 01 10:47:32 volumio volumio[5259]: 0: 0xab2a0c40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 01 10:47:32 volumio volumio[5259]: 1: 0xab2a0c40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 01 10:47:32 volumio volumio[5259]: 2: 0xab2a0c40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Oct 01 10:47:32 volumio volumio[5259]: 3: 0xab2a0c40 - ::fmt::hf1c91d83d30a63b5 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Oct 01 10:47:32 volumio volumio[5259]: 4: 0xab2c6d68 - core::fmt::write::he7b81f119cac9e82 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Oct 01 10:47:32 volumio volumio[5259]: 5: 0xab29a5d4 - std::io::Write::write_fmt::hd6a547671801193b Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Oct 01 10:47:32 volumio volumio[5259]: 6: 0xab2a2728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Oct 01 10:47:32 volumio volumio[5259]: 7: 0xab2a2728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Oct 01 10:47:32 volumio volumio[5259]: 8: 0xab2a2728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Oct 01 10:47:32 volumio volumio[5259]: 9: 0xab2a223c - std::panicking::default_hook::h3b931251a9c4b1f6 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Oct 01 10:47:32 volumio volumio[5259]: 10: 0xab2a2f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Oct 01 10:47:32 volumio volumio[5259]: 11: 0xab2a2c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Oct 01 10:47:32 volumio volumio[5259]: 12: 0xab2a11bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Oct 01 10:47:32 volumio volumio[5259]: 13: 0xab2a29ec - rust_begin_unwind Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Oct 01 10:47:32 volumio volumio[5259]: 14: 0xaad85e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Oct 01 10:47:32 volumio volumio[5259]: 15: 0xaaf62538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Oct 01 10:47:32 volumio volumio[5259]: 16: 0xaaf7caf4 - as futures::future::Future>::poll::h38eb93796db62721 Oct 01 10:47:32 volumio volumio[5259]: 17: 0xaaf5eba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Oct 01 10:47:32 volumio volumio[5259]: 18: 0xaafe8fc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Oct 01 10:47:32 volumio volumio[5259]: 19: 0xaadaedd8 - ::poll::h06f390c1cf340695 Oct 01 10:47:32 volumio volumio[5259]: 20: 0xaadc618c - futures::task_impl::std::set::h917345edbb2d7b17 Oct 01 10:47:32 volumio volumio[5259]: 21: 0xaade7f24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Oct 01 10:47:32 volumio volumio[5259]: 22: 0xaadb6924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Oct 01 10:47:32 volumio volumio[5259]: 23: 0xaade7bac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Oct 01 10:47:32 volumio volumio[5259]: 24: 0xaadd6604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Oct 01 10:47:32 volumio volumio[5259]: 25: 0xaada5da4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Oct 01 10:47:32 volumio volumio[5259]: 26: 0xaadb09a0 - vollibrespot::main::haa33a046348d736f Oct 01 10:47:32 volumio volumio[5259]: 27: 0xaadce6fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Oct 01 10:47:32 volumio volumio[5259]: 28: 0xaade6278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Oct 01 10:47:32 volumio volumio[5259]: 29: 0xab293bcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Oct 01 10:47:32 volumio volumio[5259]: 30: 0xab293bcc - std::panicking::try::do_call::h7a5efa020212c0f3 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:32 volumio volumio[5259]: 31: 0xab293bcc - std::panicking::try::h3145f518af029892 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:32 volumio volumio[5259]: 32: 0xab293bcc - std::panic::catch_unwind::h876d78503e730edb Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:32 volumio volumio[5259]: 33: 0xab293bcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Oct 01 10:47:32 volumio volumio[5259]: 34: 0xab293bcc - std::panicking::try::do_call::h6affa17582fe803e Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Oct 01 10:47:32 volumio volumio[5259]: 35: 0xab293bcc - std::panicking::try::hf3254f9f909f508b Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Oct 01 10:47:32 volumio volumio[5259]: 36: 0xab293bcc - std::panic::catch_unwind::h17abded8b6d92414 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Oct 01 10:47:32 volumio volumio[5259]: 37: 0xab293bcc - std::rt::lang_start_internal::h62925866154f59e6 Oct 01 10:47:32 volumio volumio[5259]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Oct 01 10:47:32 volumio volumio[5259]: 38: 0xaadb111c - main Oct 01 10:47:32 volumio volumio[5259]: 39: 0xf6e4a632 - __libc_start_main Oct 01 10:47:32 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 01 10:47:34 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Oct 01 10:47:34 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 01 10:47:34 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 01 10:47:34 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 01 10:47:34 volumio volumio[5275]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Oct 01 10:47:34 volumio volumio[5275]: Reading Config from "/tmp/volspotify.toml" Oct 01 10:47:34 volumio kernel: vollibrespot[5276]: syscall 397 Oct 01 10:47:34 volumio kernel: Code: 4607b4f0 46114608 e89c461a df000078 (f510bcf0) Oct 01 10:47:34 volumio kernel: CPU: 3 PID: 5276 Comm: vollibrespot Not tainted 3.14.29-amlogics905x-gf29b82a-dirty #169 Oct 01 10:47:34 volumio kernel: task: ffffffc059369000 ti: ffffffc07727c000 task.ti: ffffffc07727c000 Oct 01 10:47:34 volumio kernel: PC is at 0xf7565a42 Oct 01 10:47:34 volumio kernel: LR is at 0xab4f4c60 Oct 01 10:47:34 volumio kernel: pc : [<00000000f7565a42>] lr : [<00000000ab4f4c60>] pstate: 60010030 Oct 01 10:47:34 volumio kernel: sp : 00000000ffd326b8 Oct 01 10:47:34 volumio kernel: x12: 00000000ffd326c8 Oct 01 10:47:34 volumio kernel: x11: 00000000ffd32888 x10: 00000000ab51dbac Oct 01 10:47:34 volumio kernel: x9 : 00000000ffd33120 x8 : 0000000000001000 Oct 01 10:47:34 volumio kernel: x7 : 000000000000018d x6 : 0000000000000000 Oct 01 10:47:34 volumio kernel: x5 : 0000000000000000 x4 : 0000000000000000 Oct 01 10:47:34 volumio kernel: x3 : 0000000000000fff x2 : 0000000000000000 Oct 01 10:47:34 volumio kernel: x1 : 0000000000000000 x0 : 0000000000000000 Oct 01 10:47:34 volumio kernel: Oct 01 10:47:34 volumio volumio[5275]: [Vollibrespot] : Using Alsa backend with device: softvolume Oct 01 10:47:34 volumio volumio[5275]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Oct 01 10:47:34 volumio volumio[5275]: [Vollibrespot] : Could not connect to server: Connection refused (os error 111) Oct 01 10:47:37 volumio volumio[4947]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=c81d1a704857350dd1987a6d50fa6428" http://updates.volumio.org:7070/downloader-v1/track-device Oct 01 10:47:37 volumio volumio[4947]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 01 10:47:37 volumio volumio[4947]: Dload Upload Total Spent Left Speed Oct 01 10:47:37 volumio volumio[4947]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Oct 01 10:47:37 volumio volumio[4947]: retrying in 5 seconds, trial 1 Oct 01 10:47:37 volumio volumio[4947]: Volumio Calling Home Oct 01 10:47:40 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 01 10:47:40 volumio volumio[4947]: info: CURURI: artists:// Oct 01 10:47:43 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 10:47:43 volumio volumio[4947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 10:47:47 volumio volumio[4947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 10:47:47 volumio volumio[4947]: { Error: ENOSPC: no space left on device, write errno: -28, code: 'ENOSPC', syscall: 'write' } Oct 01 10:47:47 volumio volumio[4947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 10:47:48 volumio sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 10:46 Oct 01 10:47:48 volumio sudo[5327]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"