-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Tue 2023-03-28 01:55:01 CST. -- Mar 28 01:54:07 volumio kernel: Booting Linux on physical CPU 0x0 Mar 28 01:54:07 volumio kernel: Linux version 5.4.61 (root@fortex) (arm-linux-gnueabihf-gcc (Linaro GCC 5.3-2016.05) 5.3.1 20160412, GNU ld (Linaro_Binutils-2016.05) 2.25.0 Linaro 2016_02) #82 SMP PREEMPT Wed Aug 28 14:41:34 CST 2024 Mar 28 01:54:07 volumio kernel: CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d Mar 28 01:54:07 volumio kernel: CPU: div instructions available: patching division code Mar 28 01:54:07 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Mar 28 01:54:07 volumio kernel: OF: fdt: Machine model: TLT113-EVM TFT LCD Mar 28 01:54:07 volumio kernel: Memory policy: Data cache writealloc Mar 28 01:54:07 volumio kernel: cma: Reserved 16 MiB at 0x5f000000 Mar 28 01:54:07 volumio kernel: On node 0 totalpages: 130816 Mar 28 01:54:07 volumio kernel: Normal zone: 1152 pages used for memmap Mar 28 01:54:07 volumio kernel: Normal zone: 0 pages reserved Mar 28 01:54:07 volumio kernel: Normal zone: 130816 pages, LIFO batch:31 Mar 28 01:54:07 volumio kernel: psci: probing for conduit method from DT. Mar 28 01:54:07 volumio kernel: psci: PSCIv1.0 detected in firmware. Mar 28 01:54:07 volumio kernel: psci: Using standard PSCI v0.2 function IDs Mar 28 01:54:07 volumio kernel: psci: MIGRATE_INFO_TYPE not supported. Mar 28 01:54:07 volumio kernel: psci: SMC Calling Convention v1.0 Mar 28 01:54:07 volumio kernel: percpu: Embedded 15 pages/cpu s31296 r8192 d21952 u61440 Mar 28 01:54:07 volumio kernel: pcpu-alloc: s31296 r8192 d21952 u61440 alloc=15*4096 Mar 28 01:54:07 volumio kernel: pcpu-alloc: [0] 0 [0] 1 Mar 28 01:54:07 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 129664 Mar 28 01:54:07 volumio kernel: Kernel command line: clk_ignore_unused initcall_debug=0 console=ttyAS0,115200 loglevel=8 root=/dev/mmcblk0p5 rootwait rw init=/init partitions=boot-resource@mmcblk0p1:env@mmcblk0p2:env-redund@mmcblk0p3:boot@mmcblk0p4:rootfs@mmcblk0p5:recovery@mmcblk0p6:dsp0@mmcblk0p7:private@mmcblk0p8:UDISK@mmcblk0p9 cma=16M snum= mac_addr= wifi_mac= bt_mac= specialstr= gpt=1 androidboot.hardware=sun8iw20p1 boot_type=2 androidboot.boot_type=2 gpt=1 uboot_message=2018.05(08/28/2024-14:50:29) disp_reserve=1443840,0x5c8cc000 androidboot.dramsize=512 Mar 28 01:54:07 volumio kernel: Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 28 01:54:07 volumio kernel: Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear) Mar 28 01:54:07 volumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 28 01:54:07 volumio kernel: Memory: 478708K/523264K available (8192K kernel code, 476K rwdata, 3040K rodata, 1024K init, 328K bss, 28172K reserved, 16384K cma-reserved, 0K highmem) Mar 28 01:54:07 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Mar 28 01:54:07 volumio kernel: rcu: Preemptible hierarchical RCU implementation. Mar 28 01:54:07 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2. Mar 28 01:54:07 volumio kernel: Tasks RCU enabled. Mar 28 01:54:07 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Mar 28 01:54:07 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Mar 28 01:54:07 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Mar 28 01:54:07 volumio kernel: random: get_random_bytes called from start_kernel+0x25c/0x3e4 with crng_init=0 Mar 28 01:54:07 volumio kernel: arch_timer: cp15 timer(s) running at 24.00MHz (phys). Mar 28 01:54:07 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Mar 28 01:54:07 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Mar 28 01:54:07 volumio kernel: Switching to timer-based delay loop, resolution 41ns Mar 28 01:54:07 volumio kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns Mar 28 01:54:07 volumio kernel: Console: colour dummy device 80x30 Mar 28 01:54:07 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000) Mar 28 01:54:07 volumio kernel: pid_max: default: 32768 minimum: 301 Mar 28 01:54:07 volumio kernel: Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Mar 28 01:54:07 volumio kernel: Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Mar 28 01:54:07 volumio kernel: CPU: Testing write buffer coherency: ok Mar 28 01:54:07 volumio kernel: /cpus/cpu@0 missing clock-frequency property Mar 28 01:54:07 volumio kernel: /cpus/cpu@1 missing clock-frequency property Mar 28 01:54:07 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Mar 28 01:54:07 volumio kernel: Setting up static identity map for 0x40100000 - 0x40100060 Mar 28 01:54:07 volumio kernel: rcu: Hierarchical SRCU implementation. Mar 28 01:54:07 volumio kernel: BOOTEVENT: 3.649790: ON Mar 28 01:54:07 volumio kernel: smp: Bringing up secondary CPUs ... Mar 28 01:54:07 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Mar 28 01:54:07 volumio kernel: smp: Brought up 1 node, 2 CPUs Mar 28 01:54:07 volumio kernel: SMP: Total of 2 processors activated (96.00 BogoMIPS). Mar 28 01:54:07 volumio kernel: CPU: All CPU(s) started in SVC mode. Mar 28 01:54:07 volumio kernel: devtmpfs: initialized Mar 28 01:54:07 volumio kernel: VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 Mar 28 01:54:07 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Mar 28 01:54:07 volumio kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Mar 28 01:54:07 volumio kernel: pinctrl core: initialized pinctrl subsystem Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 16 Mar 28 01:54:07 volumio kernel: DMA: preallocated 256 KiB pool for atomic coherent allocations Mar 28 01:54:07 volumio kernel: rtc_ccu: sunxi ccu init OK Mar 28 01:54:07 volumio kernel: ccu: sunxi ccu init OK Mar 28 01:54:07 volumio kernel: r_ccu: sunxi ccu init OK Mar 28 01:54:07 volumio kernel: iommu: Default domain type: Translated Mar 28 01:54:07 volumio kernel: sunxi iommu: irq = 24 Mar 28 01:54:07 volumio kernel: SCSI subsystem initialized Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver usbfs Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver hub Mar 28 01:54:07 volumio kernel: usbcore: registered new device driver usb Mar 28 01:54:07 volumio kernel: mc: Linux media interface: v0.10 Mar 28 01:54:07 volumio kernel: videodev: Linux video capture interface: v2.00 Mar 28 01:54:07 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Mar 28 01:54:07 volumio kernel: Bluetooth: Core ver 2.22 Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 31 Mar 28 01:54:07 volumio kernel: Bluetooth: HCI device and connection manager initialized Mar 28 01:54:07 volumio kernel: Bluetooth: HCI socket layer initialized Mar 28 01:54:07 volumio kernel: Bluetooth: L2CAP socket layer initialized Mar 28 01:54:07 volumio kernel: Bluetooth: SCO socket layer initialized Mar 28 01:54:07 volumio kernel: pwm module init! Mar 28 01:54:07 volumio kernel: g2d 5410000.g2d: Adding to iommu group 0 Mar 28 01:54:07 volumio kernel: G2D: rcq version initialized.major:249 Mar 28 01:54:07 volumio kernel: input: sunxi-keyboard as /devices/virtual/input/input0 Mar 28 01:54:07 volumio kernel: [VIN_WARN]sensor_helper_probe: cannot get sensor0_cameravdd supply, setting it to NULL! Mar 28 01:54:07 volumio kernel: [VIN_WARN]sensor_helper_probe: cannot get sensor0_iovdd supply, setting it to NULL! Mar 28 01:54:07 volumio kernel: [VIN_WARN]sensor_helper_probe: cannot get sensor0_avdd supply, setting it to NULL! Mar 28 01:54:07 volumio kernel: [VIN_WARN]sensor_helper_probe: cannot get sensor0_dvdd supply, setting it to NULL! Mar 28 01:54:07 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: initialized sunXi PIO driver Mar 28 01:54:07 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 28 01:54:07 volumio kernel: thermal_sys: Registered thermal governor 'user_space' Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 2 Mar 28 01:54:07 volumio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Mar 28 01:54:07 volumio kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) Mar 28 01:54:07 volumio kernel: TCP bind hash table entries: 4096 (order: 3, 32768 bytes, linear) Mar 28 01:54:07 volumio kernel: TCP: Hash tables configured (established 4096 bind 4096) Mar 28 01:54:07 volumio kernel: UDP hash table entries: 256 (order: 1, 8192 bytes, linear) Mar 28 01:54:07 volumio kernel: UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear) Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 1 Mar 28 01:54:07 volumio kernel: RPC: Registered named UNIX socket transport module. Mar 28 01:54:07 volumio kernel: RPC: Registered udp transport module. Mar 28 01:54:07 volumio kernel: RPC: Registered tcp transport module. Mar 28 01:54:07 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 28 01:54:07 volumio kernel: Initialise system trusted keyrings Mar 28 01:54:07 volumio kernel: workingset: timestamp_bits=14 max_order=17 bucket_order=3 Mar 28 01:54:07 volumio kernel: NFS: Registering the id_resolver key type Mar 28 01:54:07 volumio kernel: Key type id_resolver registered Mar 28 01:54:07 volumio kernel: Key type id_legacy registered Mar 28 01:54:07 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Mar 28 01:54:07 volumio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Mar 28 01:54:07 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Mar 28 01:54:07 volumio kernel: fuse: init (API version 7.31) Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 38 Mar 28 01:54:07 volumio kernel: Key type asymmetric registered Mar 28 01:54:07 volumio kernel: Asymmetric key parser 'x509' registered Mar 28 01:54:07 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) Mar 28 01:54:07 volumio kernel: io scheduler mq-deadline registered Mar 28 01:54:07 volumio kernel: io scheduler kyber registered Mar 28 01:54:07 volumio kernel: atomic64_test: passed Mar 28 01:54:07 volumio kernel: [DISP]disp_module_init Mar 28 01:54:07 volumio kernel: disp 5000000.disp: Adding to iommu group 0 Mar 28 01:54:07 volumio kernel: [DISP] disp_init,line:2387: Mar 28 01:54:07 volumio kernel: smooth display screen:0 type:1 mode:4 Mar 28 01:54:07 volumio kernel: [DISP] disp_init_hdmi,line:1047: Mar 28 01:54:07 volumio kernel: dont support hdmi Mar 28 01:54:07 volumio kernel: [DISP] disp_fb_to_var,line:394: Mar 28 01:54:07 volumio kernel: [FB]not support format 5 Mar 28 01:54:07 volumio kernel: display_fb_request,fb_id:0 Mar 28 01:54:07 volumio kernel: disp_al_manager_apply ouput_type:1 Mar 28 01:54:07 volumio kernel: [DISP] lcd_clk_config,line:739: Mar 28 01:54:07 volumio kernel: disp 0, clk: pll(168000000),clk(168000000),dclk(28000000) dsi_rate(168000000) clk real:pll(192000000),clk(192000000),dclk(32000000) dsi_rate(0) Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pio supply vcc-pe not found, using dummy regulator Mar 28 01:54:07 volumio kernel: [DISP]disp_module_init finish Mar 28 01:54:07 volumio kernel: sunxi_sid_init()563 - insmod ok Mar 28 01:54:07 volumio kernel: pwm-regulator: supplied by regulator-dummy Mar 28 01:54:07 volumio kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pio supply vcc-pb not found, using dummy regulator Mar 28 01:54:07 volumio kernel: uart uart0: get regulator failed Mar 28 01:54:07 volumio kernel: uart uart0: uart0 supply uart not found, using dummy regulator Mar 28 01:54:07 volumio kernel: uart0: ttyAS0 at MMIO 0x2500000 (irq = 33, base_baud = 1500000) is a SUNXI Mar 28 01:54:07 volumio kernel: sw_console_setup()1784 - console setup baud 115200 parity n bits 8, flow n Mar 28 01:54:07 volumio kernel: free logo buffer src_phy_addr=0x5c8cc000 fb_height=960 src_stride=1504 Mar 28 01:54:07 volumio kernel: Freeing logo buffer memory: 1412K Mar 28 01:54:07 volumio kernel: printk: console [ttyAS0] enabled Mar 28 01:54:07 volumio kernel: uart uart2: get regulator failed Mar 28 01:54:07 volumio kernel: uart uart2: uart2 supply uart not found, using dummy regulator Mar 28 01:54:07 volumio kernel: uart2: ttyAS2 at MMIO 0x2500800 (irq = 34, base_baud = 1500000) is a SUNXI Mar 28 01:54:07 volumio kernel: misc dump reg init Mar 28 01:54:07 volumio kernel: [ADDR_MGT] addr_mgt_probe: module version: v1.0.10 Mar 28 01:54:07 volumio kernel: [ADDR_MGT] addr_init: Failed to get type_def_bt, use default: 0 Mar 28 01:54:07 volumio kernel: [ADDR_MGT] addr_mgt_probe: success. Mar 28 01:54:07 volumio kernel: libphy: Fixed MDIO Bus: probed Mar 28 01:54:07 volumio kernel: CAN device driver interface Mar 28 01:54:07 volumio kernel: sunxi-can can@0x0: can driver probe ok ... Mar 28 01:54:07 volumio kernel: sunxi-can can@0x1: can driver probe ok ... Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pio supply vcc-pg not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi gmac driver's version: 1.0.0 Mar 28 01:54:07 volumio kernel: gmac-power0: NULL Mar 28 01:54:07 volumio kernel: gmac-power1: NULL Mar 28 01:54:07 volumio kernel: gmac-power2: NULL Mar 28 01:54:07 volumio kernel: Failed to alloc md5 Mar 28 01:54:07 volumio kernel: eth0: Use random mac address Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver rt2500usb Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver rt73usb Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver rt2800usb Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver asix Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ax88179_178a Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver cdc_ether Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver net1080 Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver cdc_subset Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver zaurus Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver cdc_ncm Mar 28 01:54:07 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Mar 28 01:54:07 volumio kernel: sunxi-ehci: EHCI SUNXI driver Mar 28 01:54:07 volumio kernel: get ehci1-controller wakeup-source is fail. Mar 28 01:54:07 volumio kernel: sunxi ehci1-controller don't init wakeup source Mar 28 01:54:07 volumio kernel: [sunxi-ehci1]: probe, pdev->name: 4200000.ehci1-controller, sunxi_ehci: 0xc0ebb980, 0x:e083b000, irq_no:3a Mar 28 01:54:07 volumio kernel: sunxi-ehci 4200000.ehci1-controller: 4200000.ehci1-controller supply drvvbus not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-ehci 4200000.ehci1-controller: 4200000.ehci1-controller supply hci not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-ehci 4200000.ehci1-controller: EHCI Host Controller Mar 28 01:54:07 volumio kernel: sunxi-ehci 4200000.ehci1-controller: new USB bus registered, assigned bus number 1 Mar 28 01:54:07 volumio kernel: sunxi-ehci 4200000.ehci1-controller: irq 58, io mem 0x04200000 Mar 28 01:54:07 volumio kernel: sunxi-ehci 4200000.ehci1-controller: USB 2.0 started, EHCI 1.00 Mar 28 01:54:07 volumio kernel: hub 1-0:1.0: USB hub found Mar 28 01:54:07 volumio kernel: hub 1-0:1.0: 1 port detected Mar 28 01:54:07 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Mar 28 01:54:07 volumio kernel: sunxi-ohci: OHCI SUNXI driver Mar 28 01:54:07 volumio kernel: get ohci1-controller wakeup-source is fail. Mar 28 01:54:07 volumio kernel: sunxi ohci1-controller don't init wakeup source Mar 28 01:54:07 volumio kernel: [sunxi-ohci1]: probe, pdev->name: 4200400.ohci1-controller, sunxi_ohci: 0xc0ebbc10 Mar 28 01:54:07 volumio kernel: sunxi-ohci 4200400.ohci1-controller: 4200400.ohci1-controller supply drvvbus not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-ohci 4200400.ohci1-controller: 4200400.ohci1-controller supply hci not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-ohci 4200400.ohci1-controller: OHCI Host Controller Mar 28 01:54:07 volumio kernel: sunxi-ohci 4200400.ohci1-controller: new USB bus registered, assigned bus number 2 Mar 28 01:54:07 volumio kernel: sunxi-ohci 4200400.ohci1-controller: irq 59, io mem 0x04200400 Mar 28 01:54:07 volumio kernel: hub 2-0:1.0: USB hub found Mar 28 01:54:07 volumio kernel: hub 2-0:1.0: 1 port detected Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver uas Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver usb-storage Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-alauda Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-cypress Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-datafab Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums_eneub6250 Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-freecom Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-isd200 Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-jumpshot Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-karma Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-onetouch Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-realtek Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-sddr09 Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-sddr55 Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver ums-usbat Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver option Mar 28 01:54:07 volumio kernel: usbserial: USB Serial support registered for GSM modem (1-port) Mar 28 01:54:07 volumio kernel: input: sunxi-ts as /devices/platform/soc@3000000/2009c00.rtp/input/input1 Mar 28 01:54:07 volumio kernel: sunxi_gpadc_init,2137, success Mar 28 01:54:07 volumio kernel: i2c /dev entries driver Mar 28 01:54:07 volumio kernel: IR NEC protocol handler initialized Mar 28 01:54:07 volumio kernel: IR RC5(x/sz) protocol handler initialized Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver uvcvideo Mar 28 01:54:07 volumio kernel: USB Video Class driver (1.1.1) Mar 28 01:54:07 volumio kernel: gspca_main: v2.14.0 registered Mar 28 01:54:07 volumio kernel: sunxi cedar version 1.1 Mar 28 01:54:07 volumio kernel: sunxi-cedar 1c0e000.ve: Adding to iommu group 0 Mar 28 01:54:07 volumio kernel: VE: sunxi_cedar_probe power-domain init!!! Mar 28 01:54:07 volumio kernel: VE: install start!!! Mar 28 01:54:07 volumio kernel: VE: cedar-ve the get irq is 41 Mar 28 01:54:07 volumio kernel: VE: ve_debug_proc_info:(ptrval), data:(ptrval), lock:(ptrval) Mar 28 01:54:07 volumio kernel: VE: install end!!! Mar 28 01:54:07 volumio kernel: VE: sunxi_cedar_probe Mar 28 01:54:07 volumio kernel: sunxi-wdt 20500a0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) Mar 28 01:54:07 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Mar 28 01:54:07 volumio kernel: Bluetooth: HCI UART protocol H4 registered Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pio supply vcc-pc not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: SD/MMC/SDIO Host Controller Driver(v4.21 2021-05-24 16:10) Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: ***ctl-spec-caps*** 308 Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vmmc regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vqmmc regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vdmmc regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vd33sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vd18sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vq33sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: No vq18sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: Cann't get pin bias hs pinstate,check if needed Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 0Hz bm PP pm UP vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: detmode:alway in(non removable) Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: SD/MMC/SDIO Host Controller Driver(v4.21 2021-05-24 16:10) Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: ***ctl-spec-caps*** 8 Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vmmc regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vqmmc regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vdmmc regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vd33sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vd18sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vq33sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: No vq18sw regulator found Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm OD pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: Got CD GPIO Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: sdc set ios:clk 0Hz bm PP pm UP vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: no vqmmc,Check if there is regulator Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01) Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01) Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: detmode:gpio polling Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01) Mar 28 01:54:07 volumio kernel: sunxi-mmc 4020000.sdmmc: sdc set ios:clk 0Hz bm PP pm OFF vdd 0 width 1 timing LEGACY(SDR12) dt B Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: avoid to switch power_off_notification to POWERED_ON(0x01) Mar 28 01:54:07 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 400000Hz bm PP pm ON vdd 21 width 1 timing MMC-HS(SDR20) dt B Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver usbhid Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 50000000Hz bm PP pm ON vdd 21 width 1 timing MMC-HS(SDR20) dt B Mar 28 01:54:07 volumio kernel: usbhid: USB HID core driver Mar 28 01:54:07 volumio kernel: sunxi-mmc 4022000.sdmmc: sdc set ios:clk 50000000Hz bm PP pm ON vdd 21 width 4 timing MMC-HS(SDR20) dt B Mar 28 01:54:07 volumio kernel: rpmsg_tty_init: Sunxi rpmsg tty driver will init Mar 28 01:54:07 volumio kernel: mmc0: new high speed MMC card at address 0001 Mar 28 01:54:07 volumio kernel: rpmsg_tty_init: Sunxi rpmsg tty driver init ok Mar 28 01:54:07 volumio kernel: mmcblk0: mmc0:0001 08A391 7.28 GiB Mar 28 01:54:07 volumio kernel: usbcore: registered new interface driver snd-usb-audio Mar 28 01:54:07 volumio kernel: mmcblk0rpmb: mmc0:0001 08A391 partition 3 4.00 MiB, chardev (241:0) Mar 28 01:54:07 volumio kernel: sunxi-spdif 2036000.spdif: Not using gpio-spdif gpio from dts Mar 28 01:54:07 volumio kernel: mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 Mar 28 01:54:07 volumio kernel: sunxi-daudio 2032000.daudio: regulator missing or invalid Mar 28 01:54:07 volumio kernel: [AUDIOCODEC][sunxi_codec_parse_params][2412]:digital_vol:0, lineout_vol:26, mic1gain:31, mic2gain:31 pa_msleep:120, pa_level:1, pa_pwr_level:1 Mar 28 01:54:07 volumio kernel: [AUDIOCODEC][sunxi_codec_parse_params][2448]:adcdrc_cfg:0, adchpf_cfg:0, dacdrc_cfg:2, dachpf:3 Mar 28 01:54:07 volumio kernel: [AUDIOCODEC][sunxi_internal_codec_probe][2609]:codec probe finished Mar 28 01:54:07 volumio kernel: debugfs: Directory '203034c.dummy_cpudai' with parent 'audiocodec' already present! Mar 28 01:54:07 volumio kernel: [SNDCODEC][sunxi_card_init][583]:card init finished Mar 28 01:54:07 volumio kernel: sunxi-codec-machine 2030340.sound: 2030000.codec <-> 203034c.dummy_cpudai mapping ok Mar 28 01:54:07 volumio kernel: input: audiocodec sunxi Audio Jack as /devices/platform/soc@3000000/2030340.sound/sound/card0/input2 Mar 28 01:54:07 volumio kernel: [SNDCODEC][sunxi_card_dev_probe][800]:jack_enable missing, use default enable jack Mar 28 01:54:07 volumio kernel: [SNDCODEC][sunxi_card_dev_probe][810]:hp_detect_case missing, use default hp_detect_low Mar 28 01:54:07 volumio kernel: [SNDCODEC][sunxi_card_dev_probe][836]:register card finished Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 10 Mar 28 01:54:07 volumio kernel: [SNDCODEC][sunxi_hs_init_work][259]:resume-->report switch Mar 28 01:54:07 volumio kernel: Segment Routing with IPv6 Mar 28 01:54:07 volumio kernel: sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 17 Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 15 Mar 28 01:54:07 volumio kernel: can: controller area network core (rev 20170425 abi 9) Mar 28 01:54:07 volumio kernel: NET: Registered protocol family 29 Mar 28 01:54:07 volumio kernel: can: raw protocol (rev 20170425) Mar 28 01:54:07 volumio kernel: can: broadcast manager protocol (rev 20170425 t) Mar 28 01:54:07 volumio kernel: can: netlink gateway (rev 20190810) max_hops=1 Mar 28 01:54:07 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Mar 28 01:54:07 volumio kernel: Bluetooth: RFCOMM socket layer initialized Mar 28 01:54:07 volumio kernel: Bluetooth: RFCOMM ver 1.11 Mar 28 01:54:07 volumio kernel: Key type dns_resolver registered Mar 28 01:54:07 volumio kernel: Registering SWP/SWPB emulation handler Mar 28 01:54:07 volumio kernel: Loading compiled-in X.509 certificates Mar 28 01:54:07 volumio kernel: tv_probe()1527 - of_property_read_string tv_power failed! Mar 28 01:54:07 volumio kernel: get_key_map_info()179 - Failed to find "tvou" in dts. Mar 28 01:54:07 volumio kernel: tv probe finished! Mar 28 01:54:07 volumio kernel: sunxi-thermal 2009400.ths: sun8iw20 chip id: 13312 Mar 28 01:54:07 volumio kernel: sunxi-thermal 2009400.ths: sun8iw20 t1: 3000 Mar 28 01:54:07 volumio kernel: sunxi-thermal 2009400.ths: sun8iw20 cp version:17 Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pio supply vcc-pd not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-audio-card 20320a0.sounddaudio0: use dummy codec for simple card. Mar 28 01:54:07 volumio kernel: debugfs: Directory '2032000.daudio' with parent 'snddaudio0' already present! Mar 28 01:54:07 volumio kernel: sunxi-audio-card 20320a0.sounddaudio0: snd-soc-dummy-dai <-> 2032000.daudio mapping ok Mar 28 01:54:07 volumio kernel: sunxi-audio-card 203605c.soundspdif: use dummy codec for simple card. Mar 28 01:54:07 volumio kernel: debugfs: Directory '2036000.spdif' with parent 'sndspdif' already present! Mar 28 01:54:07 volumio kernel: sunxi-audio-card 203605c.soundspdif: snd-soc-dummy-dai <-> 2036000.spdif mapping ok Mar 28 01:54:07 volumio kernel: get ehci0-controller wakeup-source is fail. Mar 28 01:54:07 volumio kernel: sunxi ehci0-controller don't init wakeup source Mar 28 01:54:07 volumio kernel: [sunxi-ehci0]: probe, pdev->name: 4101000.ehci0-controller, sunxi_ehci: 0xc0ebb460, 0x:e12d0000, irq_no:38 Mar 28 01:54:07 volumio kernel: sunxi-ehci 4101000.ehci0-controller: 4101000.ehci0-controller supply hci not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-ehci 4101000.ehci0-controller: EHCI Host Controller Mar 28 01:54:07 volumio kernel: sunxi-ehci 4101000.ehci0-controller: new USB bus registered, assigned bus number 3 Mar 28 01:54:07 volumio kernel: sunxi-ehci 4101000.ehci0-controller: irq 56, io mem 0x04101000 Mar 28 01:54:07 volumio kernel: sunxi-ehci 4101000.ehci0-controller: USB 2.0 started, EHCI 1.00 Mar 28 01:54:07 volumio kernel: hub 3-0:1.0: USB hub found Mar 28 01:54:07 volumio kernel: hub 3-0:1.0: 1 port detected Mar 28 01:54:07 volumio kernel: get ohci0-controller wakeup-source is fail. Mar 28 01:54:07 volumio kernel: sunxi ohci0-controller don't init wakeup source Mar 28 01:54:07 volumio kernel: [sunxi-ohci0]: probe, pdev->name: 4101400.ohci0-controller, sunxi_ohci: 0xc0ebb6f0 Mar 28 01:54:07 volumio kernel: sunxi-ohci 4101400.ohci0-controller: 4101400.ohci0-controller supply hci not found, using dummy regulator Mar 28 01:54:07 volumio kernel: sunxi-ohci 4101400.ohci0-controller: OHCI Host Controller Mar 28 01:54:07 volumio kernel: sunxi-ohci 4101400.ohci0-controller: new USB bus registered, assigned bus number 4 Mar 28 01:54:07 volumio kernel: debugfs: Directory 'sunxi-ohci' with parent 'ohci' already present! Mar 28 01:54:07 volumio kernel: sunxi-ohci 4101400.ohci0-controller: irq 57, io mem 0x04101400 Mar 28 01:54:07 volumio kernel: hub 4-0:1.0: USB hub found Mar 28 01:54:07 volumio kernel: hub 4-0:1.0: 1 port detected Mar 28 01:54:07 volumio kernel: get det_vbus is fail, -84 Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pin PE7 already requested by 2036000.spdif; cannot claim for 5801000.csi Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: pin-135 (5801000.csi) status -22 Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: could not request pin 135 (PE7) from group PE7 on device pio Mar 28 01:54:07 volumio kernel: vin_csi 5801000.csi: Error applying setting, reverse things back Mar 28 01:54:07 volumio kernel: vin_csi: probe of 5801000.csi failed with error -22 Mar 28 01:54:07 volumio kernel: sunxi-vin-core 5809000.vinc: Adding to iommu group 0 Mar 28 01:54:07 volumio kernel: sunxi-vin-core 5809200.vinc: Adding to iommu group 0 Mar 28 01:54:07 volumio kernel: sun8iw20-pinctrl pio: missing pins property in node csi_mclk0 Mar 28 01:54:07 volumio kernel: sunxi-vin-media: probe of 5800800.vind failed with error -22 Mar 28 01:54:07 volumio kernel: OF: fdt: not creating '/sys/firmware/fdt': CRC check failed Mar 28 01:54:07 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 28 01:54:07 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 28 01:54:07 volumio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Mar 28 01:54:07 volumio kernel: clk: Not disabling unused clocks Mar 28 01:54:07 volumio kernel: cfg80211: failed to load regulatory.db Mar 28 01:54:07 volumio kernel: ALSA device list: Mar 28 01:54:07 volumio kernel: #0: audiocodec Mar 28 01:54:07 volumio kernel: #1: snddaudio0 Mar 28 01:54:07 volumio kernel: #2: sndspdif Mar 28 01:54:07 volumio kernel: alloc_fd: slot 0 not NULL! Mar 28 01:54:07 volumio kernel: [SNDCODEC][sunxi_check_hs_detect_status][191]:plugin --> switch:1 Mar 28 01:54:07 volumio kernel: EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null) Mar 28 01:54:07 volumio kernel: VFS: Mounted root (ext4 filesystem) on device 179:5. Mar 28 01:54:07 volumio kernel: Freeing unused kernel memory: 1024K Mar 28 01:54:07 volumio kernel: Kernel init done Mar 28 01:54:07 volumio kernel: Run /init as init process Mar 28 01:54:07 volumio kernel: random: fast init done Mar 28 01:54:07 volumio systemd[1]: System time before build time, advancing clock. Mar 28 01:54:07 volumio systemd[1]: Failed to find module 'autofs4' Mar 28 01:54:07 volumio systemd[1]: systemd 245.4-4ubuntu3.22 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid) Mar 28 01:54:07 volumio systemd[1]: Detected architecture arm. Mar 28 01:54:07 volumio systemd[1]: Set hostname to . Mar 28 01:54:07 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 28 01:54:07 volumio systemd[1]: Created slice system-getty.slice. Mar 28 01:54:07 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 28 01:54:07 volumio systemd[1]: Created slice system-modprobe.slice. Mar 28 01:54:07 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 28 01:54:07 volumio systemd[1]: Created slice system-serial\x2dgetty.slice. Mar 28 01:54:07 volumio systemd[1]: Created slice User and Session Slice. Mar 28 01:54:07 volumio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Mar 28 01:54:07 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Mar 28 01:54:07 volumio systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. Mar 28 01:54:07 volumio systemd[1]: Reached target Local Encrypted Volumes. Mar 28 01:54:07 volumio systemd[1]: Reached target Paths. Mar 28 01:54:07 volumio systemd[1]: Reached target Remote File Systems. Mar 28 01:54:07 volumio systemd[1]: Reached target Slices. Mar 28 01:54:07 volumio systemd[1]: Listening on initctl Compatibility Named Pipe. Mar 28 01:54:07 volumio systemd[1]: Condition check resulted in Journal Audit Socket being skipped. Mar 28 01:54:07 volumio systemd[1]: Listening on Journal Socket (/dev/log). Mar 28 01:54:07 volumio systemd[1]: Listening on Journal Socket. Mar 28 01:54:07 volumio systemd[1]: Listening on udev Control Socket. Mar 28 01:54:07 volumio systemd[1]: Listening on udev Kernel Socket. Mar 28 01:54:07 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 28 01:54:07 volumio systemd[1]: Mounting POSIX Message Queue File System... Mar 28 01:54:07 volumio systemd[1]: Mounting Kernel Debug File System... Mar 28 01:54:07 volumio systemd[1]: Condition check resulted in Kernel Trace File System being skipped. Mar 28 01:54:07 volumio systemd[1]: Starting Journal Service... Mar 28 01:54:07 volumio systemd[1]: Started dynamicswap service. Mar 28 01:54:07 volumio systemd[1]: Reached target Swap. Mar 28 01:54:07 volumio systemd[1]: Condition check resulted in Create list of static device nodes for the current kernel being skipped. Mar 28 01:54:07 volumio systemd[1]: Starting Load Kernel Module chromeos_pstore... Mar 28 01:54:07 volumio systemd-journald[1269]: Journal started Mar 28 01:54:07 volumio systemd-journald[1269]: Runtime Journal (/run/log/journal/a582bff9384b4b2688f3db456adf86c8) is 3.7M, max 30.0M, 26.2M free. Mar 28 01:54:07 volumio volumio[1270]: 512 MB or less RAM Detected, need to enable swap Mar 28 01:54:08 volumio systemd[1]: Starting Load Kernel Module drm... Mar 28 01:54:08 volumio systemd[1]: Starting Load Kernel Module efi_pstore... Mar 28 01:54:08 volumio systemd[1]: Starting Load Kernel Module pstore_blk... Mar 28 01:54:08 volumio systemd[1]: Starting Load Kernel Module pstore_zone... Mar 28 01:54:08 volumio systemd[1]: Starting Load Kernel Module ramoops... Mar 28 01:54:08 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 28 01:54:08 volumio volumio[1279]: mount: /swap: can't find LABEL="volumio_data". Mar 28 01:54:08 volumio systemd[1]: Starting Load Kernel Modules... Mar 28 01:54:08 volumio volumio[1270]: Enabling Swap Mar 28 01:54:08 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Mar 28 01:54:08 volumio systemd[1]: Starting udev Coldplug all Devices... Mar 28 01:54:08 volumio systemd[1]: Started Journal Service. Mar 28 01:54:08 volumio systemd[1]: Mounted POSIX Message Queue File System. Mar 28 01:54:08 volumio systemd[1]: Mounted Kernel Debug File System. Mar 28 01:54:08 volumio systemd[1]: modprobe@chromeos_pstore.service: Succeeded. Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Module chromeos_pstore. Mar 28 01:54:08 volumio kernel: Adding 524284k swap on /swap/swapfile. Priority:-2 extents:1 across:524284k SS Mar 28 01:54:08 volumio volumio[1270]: Setting swappiness to 40 Mar 28 01:54:08 volumio systemd[1]: modprobe@drm.service: Succeeded. Mar 28 01:54:08 volumio volumio[1544]: vm.swappiness = 40 Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Module drm. Mar 28 01:54:08 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 28 01:54:08 volumio systemd[1]: modprobe@efi_pstore.service: Succeeded. Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Module efi_pstore. Mar 28 01:54:08 volumio systemd[1]: modprobe@pstore_blk.service: Succeeded. Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Module pstore_blk. Mar 28 01:54:08 volumio systemd[1]: modprobe@pstore_zone.service: Succeeded. Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Module pstore_zone. Mar 28 01:54:08 volumio systemd[1]: modprobe@ramoops.service: Succeeded. Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Module ramoops. Mar 28 01:54:08 volumio systemd[1]: Finished Load Kernel Modules. Mar 28 01:54:08 volumio systemd[1]: Finished Remount Root and Kernel File Systems. Mar 28 01:54:08 volumio systemd[1]: Mounting FUSE Control File System... Mar 28 01:54:08 volumio systemd[1]: Mounting Kernel Configuration File System... Mar 28 01:54:08 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 28 01:54:08 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Mar 28 01:54:08 volumio systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Mar 28 01:54:08 volumio systemd[1]: Starting Load/Save Random Seed... Mar 28 01:54:08 volumio systemd-journald[1269]: Runtime Journal (/run/log/journal/a582bff9384b4b2688f3db456adf86c8) is 3.7M, max 30.0M, 26.2M free. Mar 28 01:54:08 volumio systemd[1]: Starting Apply Kernel Variables... Mar 28 01:54:08 volumio systemd[1]: Starting Create System Users... Mar 28 01:54:08 volumio systemd-random-seed[1550]: Kernel entropy pool is not initialized yet, waiting until it is. Mar 28 01:54:08 volumio systemd[1]: Mounted FUSE Control File System. Mar 28 01:54:09 volumio systemd-sysctl[1551]: Couldn't write '1' to 'kernel/yama/ptrace_scope', ignoring: No such file or directory Mar 28 01:54:09 volumio systemd-sysctl[1551]: Not setting net/ipv4/conf/all/promote_secondaries (explicit setting exists). Mar 28 01:54:09 volumio systemd-sysctl[1551]: Not setting net/ipv4/conf/default/promote_secondaries (explicit setting exists). Mar 28 01:54:09 volumio systemd-sysctl[1551]: Couldn't write 'fq_codel' to 'net/core/default_qdisc', ignoring: No such file or directory Mar 28 01:54:09 volumio systemd[1]: Mounted Kernel Configuration File System. Mar 28 01:54:09 volumio systemd[1]: Finished Flush Journal to Persistent Storage. Mar 28 01:54:09 volumio systemd[1]: Finished Apply Kernel Variables. Mar 28 01:54:09 volumio systemd[1]: Finished Create System Users. Mar 28 01:54:09 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Mar 28 01:54:09 volumio systemd[1]: Finished udev Coldplug all Devices. Mar 28 01:54:09 volumio systemd[1]: Finished Create Static Device Nodes in /dev. Mar 28 01:54:09 volumio systemd[1]: Reached target Local File Systems (Pre). Mar 28 01:54:09 volumio systemd[1]: Starting udev Kernel Device Manager... Mar 28 01:54:09 volumio systemd[1]: Started udev Kernel Device Manager. Mar 28 01:54:09 volumio systemd[1]: Starting Network Service... Mar 28 01:54:09 volumio systemd-udevd[2026]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 28 01:54:09 volumio systemd-networkd[2027]: Configuration file /etc/systemd/network/10-eth0.network is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Mar 28 01:54:09 volumio systemd-udevd[2024]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 28 01:54:09 volumio systemd-networkd[2027]: Configuration file /etc/systemd/network/11-wlan0.network is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Mar 28 01:54:09 volumio systemd-networkd[2027]: Enumeration completed Mar 28 01:54:09 volumio systemd[1]: Started Network Service. Mar 28 01:54:10 volumio systemd[1]: Found device /dev/ttyAS0. Mar 28 01:54:10 volumio systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. Mar 28 01:54:10 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 28 01:54:10 volumio systemd[1]: Condition check resulted in Kernel Trace File System being skipped. Mar 28 01:54:10 volumio systemd[1]: Condition check resulted in Create list of static device nodes for the current kernel being skipped. Mar 28 01:54:10 volumio systemd[1]: Starting Load Kernel Module chromeos_pstore... Mar 28 01:54:10 volumio systemd-udevd[2017]: Using default interface naming scheme 'v245'. Mar 28 01:54:10 volumio systemd[1]: Starting Load Kernel Module efi_pstore... Mar 28 01:54:10 volumio systemd-udevd[2017]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 28 01:54:10 volumio systemd-udevd[2017]: ethtool: could not get ethtool features for eth0 Mar 28 01:54:10 volumio systemd-udevd[2017]: Could not set offload features of eth0: Device or resource busy Mar 28 01:54:10 volumio systemd[1]: Starting Load Kernel Module pstore_blk... Mar 28 01:54:10 volumio systemd-networkd[2027]: eth0: IPv6 successfully enabled Mar 28 01:54:10 volumio systemd[1]: Starting Load Kernel Module pstore_zone... Mar 28 01:54:10 volumio systemd[1]: Starting Load Kernel Module ramoops... Mar 28 01:54:10 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 28 01:54:10 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 28 01:54:10 volumio systemd[1]: modprobe@chromeos_pstore.service: Succeeded. Mar 28 01:54:10 volumio systemd[1]: Finished Load Kernel Module chromeos_pstore. Mar 28 01:54:10 volumio systemd[1]: modprobe@efi_pstore.service: Succeeded. Mar 28 01:54:10 volumio systemd[1]: Finished Load Kernel Module efi_pstore. Mar 28 01:54:10 volumio systemd[1]: modprobe@pstore_blk.service: Succeeded. Mar 28 01:54:10 volumio systemd[1]: Finished Load Kernel Module pstore_blk. Mar 28 01:54:10 volumio systemd[1]: modprobe@pstore_zone.service: Succeeded. Mar 28 01:54:10 volumio systemd[1]: Finished Load Kernel Module pstore_zone. Mar 28 01:54:10 volumio systemd[1]: modprobe@ramoops.service: Succeeded. Mar 28 01:54:10 volumio systemd[1]: Finished Load Kernel Module ramoops. Mar 28 01:54:10 volumio kernel: libphy: 4500000.eth: probed Mar 28 01:54:11 volumio kernel: sunxi-gmac 4500000.eth eth0: eth0: Type(8) PHY ID 0000011a at 0 IRQ poll (4500000.eth-0:00) Mar 28 01:54:11 volumio systemd-networkd[2027]: eth0: Link UP Mar 28 01:54:11 volumio systemd-udevd[2023]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 28 01:54:11 volumio systemd-udevd[2019]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 28 01:54:11 volumio systemd-udevd[2023]: Using default interface naming scheme 'v245'. Mar 28 01:54:11 volumio systemd-udevd[2023]: ip6tnl0: Could not generate persistent MAC: No data available Mar 28 01:54:11 volumio systemd-udevd[2021]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 28 01:54:11 volumio systemd[1]: Found device /dev/mmcblk0p9. Mar 28 01:54:11 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 28 01:54:11 volumio kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Mar 28 01:54:11 volumio systemd[1]: Mounting /mnt/UDISK... Mar 28 01:54:11 volumio kernel: EXT4-fs (mmcblk0p9): mounted filesystem with ordered data mode. Opts: (null) Mar 28 01:54:11 volumio systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Mar 28 01:54:11 volumio systemd[1]: Mounted /mnt/UDISK. Mar 28 01:54:11 volumio systemd[1]: Reached target Local File Systems. Mar 28 01:54:11 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 28 01:54:11 volumio systemd[1]: Starting Create Volatile Files and Directories... Mar 28 01:54:11 volumio systemd[1]: Finished Create Volatile Files and Directories. Mar 28 01:54:11 volumio systemd[1]: Starting Network Name Resolution... Mar 28 01:54:11 volumio systemd[1]: Starting Network Time Synchronization... Mar 28 01:54:11 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 28 01:54:12 volumio systemd[1]: Finished Update UTMP about System Boot/Shutdown. Mar 28 01:54:12 volumio systemd-resolved[2120]: Positive Trust Anchors: Mar 28 01:54:12 volumio systemd-resolved[2120]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 28 01:54:12 volumio systemd-resolved[2120]: Negative trust anchors: 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Mar 28 01:54:12 volumio systemd[1]: Started Network Time Synchronization. Mar 28 01:54:12 volumio systemd[1]: Reached target System Initialization. Mar 28 01:54:12 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 28 01:54:12 volumio systemd[1]: Reached target System Time Set. Mar 28 01:54:12 volumio systemd-resolved[2120]: Using system hostname 'volumio'. Mar 28 01:54:12 volumio systemd[1]: Reached target System Time Synchronized. Mar 28 01:54:12 volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2023-03-28 01:54:12 CST as it is in the future. Mar 28 01:54:12 volumio systemd[1]: Started Daily apt download activities. Mar 28 01:54:12 volumio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2023-03-28 01:54:12 CST as it is in the future. Mar 28 01:54:12 volumio systemd[1]: Started Daily apt upgrade and clean activities. Mar 28 01:54:12 volumio systemd[1]: e2scrub_all.timer: Not using persistent file timestamp Tue 2023-03-28 01:54:12 CST as it is in the future. Mar 28 01:54:12 volumio systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. Mar 28 01:54:12 volumio systemd[1]: fstrim.timer: Not using persistent file timestamp Tue 2023-03-28 01:54:13 CST as it is in the future. Mar 28 01:54:12 volumio systemd[1]: Started Discard unused blocks once a week. Mar 28 01:54:12 volumio systemd[1]: logrotate.timer: Not using persistent file timestamp Tue 2023-03-28 01:54:13 CST as it is in the future. Mar 28 01:54:12 volumio systemd[1]: Started Daily rotation of log files. Mar 28 01:54:12 volumio systemd[1]: motd-news.timer: Not using persistent file timestamp Tue 2023-03-28 01:54:13 CST as it is in the future. Mar 28 01:54:12 volumio systemd[1]: Started Message of the Day. Mar 28 01:54:12 volumio systemd[1]: Reached target Timers. Mar 28 01:54:12 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 28 01:54:12 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 28 01:54:12 volumio systemd[1]: Listening on mpd.socket. Mar 28 01:54:12 volumio systemd[1]: Reached target Sockets. Mar 28 01:54:12 volumio systemd[1]: Reached target Basic System. Mar 28 01:54:12 volumio systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. Mar 28 01:54:12 volumio systemd[1]: Starting Save/Restore Sound Card State... Mar 28 01:54:13 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 28 01:54:13 volumio systemd[1]: Started D-Bus System Message Bus. Mar 28 01:54:13 volumio avahi-daemon[2126]: Found user 'avahi' (UID 107) and group 'avahi' (GID 114). Mar 28 01:54:13 volumio avahi-daemon[2126]: Successfully dropped root privileges. Mar 28 01:54:13 volumio avahi-daemon[2126]: avahi-daemon 0.7 starting up. Mar 28 01:54:13 volumio systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... Mar 28 01:54:13 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 28 01:54:13 volumio systemd[1]: Started Volumio Iptables Module. Mar 28 01:54:13 volumio systemd[1]: Starting Dispatcher daemon for systemd-networkd... Mar 28 01:54:13 volumio systemd[1]: Started Set the CPU Frequency Scaling governor. Mar 28 01:54:13 volumio kernel: random: crng init done Mar 28 01:54:13 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Mar 28 01:54:13 volumio avahi-daemon[2126]: Successfully called chroot(). Mar 28 01:54:13 volumio avahi-daemon[2126]: Successfully dropped remaining capabilities. Mar 28 01:54:13 volumio systemd[1]: Started SW Update Web Service. Mar 28 01:54:13 volumio avahi-daemon[2126]: No service file found in /etc/avahi/services. Mar 28 01:54:13 volumio avahi-daemon[2126]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Mar 28 01:54:13 volumio systemd[2141]: swupdate_web.service: Failed to execute command: Exec format error Mar 28 01:54:13 volumio systemd[2141]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Mar 28 01:54:13 volumio avahi-daemon[2126]: New relevant interface lo.IPv4 for mDNS. Mar 28 01:54:13 volumio avahi-daemon[2126]: Network interface enumeration completed. Mar 28 01:54:13 volumio avahi-daemon[2126]: Registering new address record for 127.0.0.1 on lo.IPv4. Mar 28 01:54:13 volumio systemd[1]: Starting Login Service... Mar 28 01:54:13 volumio systemd[1]: Starting LSB: Start busybox udhcpd at boot time... Mar 28 01:54:13 volumio systemd[1]: Started mount disks automatically with standby. Mar 28 01:54:13 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 28 01:54:13 volumio systemd[1]: Started Volumio First Start Script. Mar 28 01:54:13 volumio systemd[1]: Started Volumio Log Rotation Service. Mar 28 01:54:13 volumio systemd[1]: Started Volumio SSH enabler. Mar 28 01:54:13 volumio volumio_first_start.sh[2159]: no find file Mar 28 01:54:13 volumio systemd[1]: Starting Wireless Services... Mar 28 01:54:13 volumio dbus-daemon[2127]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.4' (uid=1000 pid=2149 comm="/usr/bin/udisks-glue --foreground ") Mar 28 01:54:13 volumio systemd[1]: Starting WPA supplicant... Mar 28 01:54:13 volumio systemd[1]: Started Network Name Resolution. Mar 28 01:54:13 volumio systemd[1]: Finished Load/Save Random Seed. Mar 28 01:54:13 volumio systemd[1]: e2scrub_reap.service: Succeeded. Mar 28 01:54:13 volumio systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Mar 28 01:54:13 volumio systemd[1]: iptables.service: Succeeded. Mar 28 01:54:13 volumio systemd-logind[2144]: New seat seat0. Mar 28 01:54:13 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Mar 28 01:54:13 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Mar 28 01:54:13 volumio systemd[1]: volumiossh.service: Succeeded. Mar 28 01:54:13 volumio wpa_supplicant[2166]: Successfully initialized wpa_supplicant Mar 28 01:54:13 volumio systemd[1]: Finished Save/Restore Sound Card State. Mar 28 01:54:13 volumio dbus-daemon[2127]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 28 01:54:13 volumio sudo[2165]: pam_unix(sudo:account): account root has password changed in future Mar 28 01:54:13 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 1. Mar 28 01:54:13 volumio sudo[2165]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Mar 28 01:54:13 volumio systemd[1]: Started Login Service. Mar 28 01:54:13 volumio udhcpd[2147]: udhcpd: Disabled. Edit /etc/default/udhcpd to enable it. Mar 28 01:54:14 volumio systemd[1]: Started LSB: Start busybox udhcpd at boot time. Mar 28 01:54:14 volumio systemd[1]: Started WPA supplicant. Mar 28 01:54:14 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:14 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 28 01:54:14 volumio avahi-daemon[2126]: Server startup complete. Host name is volumio.local. Local service cookie is 1375497274. Mar 28 01:54:14 volumio systemd[1]: Reached target Network. Mar 28 01:54:14 volumio systemd[1]: Reached target Network is Online. Mar 28 01:54:14 volumio wifistart.sh[2222]: command failed: No such device (-19) Mar 28 01:54:14 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:14 volumio systemd[1]: Reached target Host and Network Name Lookups. Mar 28 01:54:14 volumio volumio[2155]: Could not open config: /tmp/upmpdcli.conf Mar 28 01:54:14 volumio systemd[1]: Reached target Sound Card. Mar 28 01:54:14 volumio systemd[1]: Starting Music Player Daemon... Mar 28 01:54:14 volumio systemd[1]: Starting Samba NMB Daemon... Mar 28 01:54:14 volumio systemd[1]: Started NQPTP -- Not Quite PTP. Mar 28 01:54:14 volumio systemd[1]: Starting /etc/rc.local Compatibility... Mar 28 01:54:14 volumio systemd[1]: Stopped SW Update Web Service. Mar 28 01:54:14 volumio systemd[1]: Started SW Update Web Service. Mar 28 01:54:14 volumio systemd-networkd[2027]: eth0: Gained carrier Mar 28 01:54:14 volumio systemd[2233]: swupdate_web.service: Failed to execute command: Exec format error Mar 28 01:54:14 volumio kernel: sunxi-gmac 4500000.eth eth0: Link is Up - 1Gbps/Full - flow control off Mar 28 01:54:14 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 28 01:54:14 volumio systemd[2233]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Mar 28 01:54:14 volumio systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Mar 28 01:54:14 volumio systemd[1]: Starting Permit User Sessions... Mar 28 01:54:14 volumio systemd[1]: Starting Disk Manager (legacy version)... Mar 28 01:54:14 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 28 01:54:14 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 28 01:54:14 volumio systemd[1]: Started /etc/rc.local Compatibility. Mar 28 01:54:14 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Mar 28 01:54:14 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Mar 28 01:54:14 volumio dbus-daemon[2127]: [system] Successfully activated service 'org.freedesktop.UDisks' Mar 28 01:54:14 volumio systemd[1]: Finished Permit User Sessions. Mar 28 01:54:14 volumio systemd[1]: Started Disk Manager (legacy version). Mar 28 01:54:14 volumio systemd[1]: Started Getty on tty1. Mar 28 01:54:14 volumio systemd[1]: Started Serial Getty on ttyAS0. Mar 28 01:54:14 volumio dbus-daemon[2127]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.9' (uid=0 pid=2235 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Mar 28 01:54:14 volumio systemd[1]: Reached target Login Prompts. Mar 28 01:54:14 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 2. Mar 28 01:54:14 volumio systemd[1]: Starting Authorization Manager... Mar 28 01:54:14 volumio systemd[1]: Stopped SW Update Web Service. Mar 28 01:54:15 volumio systemd[1]: Started SW Update Web Service. Mar 28 01:54:15 volumio systemd[2246]: swupdate_web.service: Failed to execute command: Exec format error Mar 28 01:54:15 volumio systemd[2246]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Mar 28 01:54:15 volumio polkitd[2242]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 28 01:54:15 volumio dbus-daemon[2127]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 28 01:54:15 volumio systemd[1]: Started Authorization Manager. Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0p6 inserted Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0 inserted Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0p4 inserted Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 3. Mar 28 01:54:15 volumio systemd[1]: Stopped SW Update Web Service. Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0p3 inserted Mar 28 01:54:15 volumio systemd[1]: Started SW Update Web Service. Mar 28 01:54:15 volumio systemd[2247]: swupdate_web.service: Failed to execute command: Exec format error Mar 28 01:54:15 volumio systemd[2247]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0p2 inserted Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0p8 inserted Mar 28 01:54:15 volumio udisks-glue[2149]: Device file /dev/mmcblk0p1 inserted Mar 28 01:54:15 volumio udisks-glue[2149]: Trying to automount /dev/mmcblk0p1... Mar 28 01:54:15 volumio nmbd[2230]: [2023/03/28 01:54:15.711475, 0] ../../source3/nmbd/nmbd.c:901(main) Mar 28 01:54:15 volumio nmbd[2230]: nmbd version 4.15.13-Ubuntu started. Mar 28 01:54:15 volumio nmbd[2230]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 4. Mar 28 01:54:15 volumio nmbd[2230]: [2023/03/28 01:54:15.736202, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 28 01:54:15 volumio nmbd[2230]: started asyncdns process 2253 Mar 28 01:54:15 volumio nmbd[2230]: [2023/03/28 01:54:15.740122, 0] ../../lib/util/become_daemon.c:150(daemon_status) Mar 28 01:54:15 volumio nmbd[2230]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 28 01:54:15 volumio nmbd[2230]: [2023/03/28 01:54:15.741715, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Mar 28 01:54:15 volumio nmbd[2230]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 28 01:54:15 volumio systemd[1]: Stopped SW Update Web Service. Mar 28 01:54:15 volumio systemd[1]: Started SW Update Web Service. Mar 28 01:54:15 volumio systemd[2254]: swupdate_web.service: Failed to execute command: Exec format error Mar 28 01:54:15 volumio systemd[2254]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Mar 28 01:54:15 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Mar 28 01:54:16 volumio avahi-daemon[2126]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::8436:f5ff:fee0:2f71. Mar 28 01:54:16 volumio systemd-networkd[2027]: eth0: Gained IPv6LL Mar 28 01:54:16 volumio avahi-daemon[2126]: New relevant interface eth0.IPv6 for mDNS. Mar 28 01:54:16 volumio systemd-timesyncd[2121]: Network configuration changed, trying to establish connection. Mar 28 01:54:16 volumio avahi-daemon[2126]: Registering new address record for fe80::8436:f5ff:fee0:2f71 on eth0.*. Mar 28 01:54:16 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 5. Mar 28 01:54:16 volumio systemd[1]: Stopped SW Update Web Service. Mar 28 01:54:16 volumio systemd[1]: swupdate_web.service: Start request repeated too quickly. Mar 28 01:54:16 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Mar 28 01:54:16 volumio systemd[1]: Failed to start SW Update Web Service. Mar 28 01:54:16 volumio systemd[1]: Started Dispatcher daemon for systemd-networkd. Mar 28 01:54:16 volumio avahi-daemon[2126]: Withdrawing address record for 127.0.0.1 on lo. Mar 28 01:54:16 volumio avahi-daemon[2126]: Host name conflict, retrying with volumio-2 Mar 28 01:54:16 volumio avahi-daemon[2126]: Registering new address record for fe80::8436:f5ff:fee0:2f71 on eth0.*. Mar 28 01:54:16 volumio avahi-daemon[2126]: Registering new address record for 127.0.0.1 on lo.IPv4. Mar 28 01:54:16 volumio udisks-glue[2149]: Successfully automounted /dev/mmcblk0p1 at /media/Volumn Mar 28 01:54:16 volumio udisks-glue[2149]: Device file /dev/mmcblk0p7 inserted Mar 28 01:54:16 volumio udisks-glue[2149]: Device file /dev/mmcblk0p1 mounted at /media/Volumn Mar 28 01:54:16 volumio systemd-networkd[2027]: eth0: DHCPv4 address 192.168.23.163/24 via 192.168.23.1 Mar 28 01:54:16 volumio avahi-daemon[2126]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.23.163. Mar 28 01:54:16 volumio avahi-daemon[2126]: New relevant interface eth0.IPv4 for mDNS. Mar 28 01:54:16 volumio avahi-daemon[2126]: Registering new address record for 192.168.23.163 on eth0.IPv4. Mar 28 01:54:16 volumio systemd-timesyncd[2121]: Network configuration changed, trying to establish connection. Mar 28 01:54:16 volumio systemd-timesyncd[2121]: Network configuration changed, trying to establish connection. Mar 28 01:54:16 volumio systemd-timesyncd[2121]: Network configuration changed, trying to establish connection. Mar 28 01:54:16 volumio systemd[1]: Started Samba NMB Daemon. Mar 28 01:54:16 volumio systemd[1]: Starting Samba SMB Daemon... Mar 28 01:54:16 volumio nmbd[2230]: [2023/03/28 01:54:16.553282, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 28 01:54:16 volumio nmbd[2230]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.163 for name WORKGROUP<1d>. Mar 28 01:54:16 volumio nmbd[2230]: This response was from IP 192.168.23.81, reporting an IP address of 192.168.23.81. Mar 28 01:54:17 volumio nmbd[2230]: [2023/03/28 01:54:17.164138, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Mar 28 01:54:17 volumio nmbd[2230]: my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.23.163. Mar 28 01:54:17 volumio nmbd[2230]: [2023/03/28 01:54:17.164810, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Mar 28 01:54:17 volumio nmbd[2230]: standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.23.163 Mar 28 01:54:17 volumio nmbd[2230]: [2023/03/28 01:54:17.165136, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Mar 28 01:54:17 volumio nmbd[2230]: my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.23.163. Mar 28 01:54:17 volumio nmbd[2230]: [2023/03/28 01:54:17.165449, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Mar 28 01:54:17 volumio nmbd[2230]: standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.23.163 Mar 28 01:54:17 volumio nmbd[2230]: [2023/03/28 01:54:17.165722, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Mar 28 01:54:17 volumio nmbd[2230]: my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.23.163. Mar 28 01:54:17 volumio nmbd[2230]: [2023/03/28 01:54:17.165990, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Mar 28 01:54:17 volumio nmbd[2230]: standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.23.163 Mar 28 01:54:17 volumio smbd[2264]: [2023/03/28 01:54:17.377971, 0] ../../source3/smbd/server.c:1734(main) Mar 28 01:54:17 volumio smbd[2264]: smbd version 4.15.13-Ubuntu started. Mar 28 01:54:17 volumio smbd[2264]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Mar 28 01:54:17 volumio avahi-daemon[2126]: Server startup complete. Host name is volumio-2.local. Local service cookie is 1375497274. Mar 28 01:54:17 volumio systemd[1]: Started Samba SMB Daemon. Mar 28 01:54:18 volumio set-cpufreq[2140]: Setting ondemand scheduler for all CPUs Mar 28 01:54:18 volumio systemd[1]: ondemand.service: Succeeded. Mar 28 01:54:18 volumio wireless.js[2236]: WIRELESS: No wireless interface, exiting Mar 28 01:54:18 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Mar 28 01:54:18 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Mar 28 01:54:18 volumio systemd[1]: Failed to start Wireless Services. Mar 28 01:54:18 volumio systemd[1]: Started Volumio Backend Module. Mar 28 01:54:18 volumio systemd[1]: Started First Start. Mar 28 01:54:18 volumio systemd[1]: Started SocketIO Bridge Service. Mar 28 01:54:18 volumio firststart.sh[2278]: Volumio first start configuration script Mar 28 01:54:18 volumio firststart.sh[2278]: configuring unconfigured packages Mar 28 01:54:18 volumio systemd[1]: Started Demo Service. Mar 28 01:54:18 volumio socketio[2279]: websocket connect Mar 28 01:54:18 volumio socketio[2279]: Error: No active session Mar 28 01:54:18 volumio socketio[2279]: [2023-03-28 01:54:18] [info] asio async_connect error: asio.system:111 (Connection refused) Mar 28 01:54:18 volumio socketio[2279]: [2023-03-28 01:54:18] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Mar 28 01:54:18 volumio socketio[2279]: [2023-03-28 01:54:18] [error] handle_connect error: Connection refused Mar 28 01:54:18 volumio demo[2281]: opt = r Mar 28 01:54:18 volumio demo[2281]: optarg = 90 Mar 28 01:54:18 volumio demo[2281]: optind = 3 Mar 28 01:54:18 volumio demo[2281]: argv[optind - 1] = 90 Mar 28 01:54:18 volumio demo[2281]: opt = t Mar 28 01:54:18 volumio demo[2281]: optarg = /dev/ttyAS2 Mar 28 01:54:18 volumio demo[2281]: optind = 5 Mar 28 01:54:18 volumio demo[2281]: argv[optind - 1] = /dev/ttyAS2 Mar 28 01:54:18 volumio demo[2281]: tty driver /dev/ttyAS2 Mar 28 01:54:18 volumio demo[2281]: SCREEN_SIZE_960X376 Mar 28 01:54:18 volumio demo[2293]: rm: cannot remove '/mylog.txt': No such file or directory Mar 28 01:54:18 volumio mpd[2229]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Mar 28 01:54:18 volumio mpd[2229]: config_file: config parameter "buffer_before_play" on line 85 is deprecated Mar 28 01:54:18 volumio mpd[2229]: config_file: config parameter "id3v1_encoding" on line 99 is deprecated Mar 28 01:54:19 volumio mpd[2229]: Mar 28 01:54 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 01:54:19 volumio mpd[2229]: Mar 28 01:54 : exception: Database corrupted Mar 28 01:54:19 volumio mpd[2229]: Mar 28 01:54 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 28 01:54:19 volumio mpd[2229]: Mar 28 01:54 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 28 01:54:19 volumio systemd[1]: Started Music Player Daemon. Mar 28 01:54:19 volumio demo[2300]: cat: /workdir/version.txt: No such file or directory Mar 28 01:54:19 volumio systemd[1]: Reached target Multi-User System. Mar 28 01:54:19 volumio systemd[1]: Reached target Graphical Interface. Mar 28 01:54:19 volumio udisks-glue[2259]: Updating DB (#1) ... Mar 28 01:54:19 volumio udisks-glue[2259]: volume: n/a repeat: off random: off single: off consume: off Mar 28 01:54:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 28 01:54:19 volumio firststart.sh[2278]: Creating /var/log/samba folder Mar 28 01:54:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 28 01:54:19 volumio firststart.sh[2316]: mkdir: cannot create directory ‘/var/log/samba’: File exists Mar 28 01:54:19 volumio systemd[1]: Finished Update UTMP about System Runlevel Changes. Mar 28 01:54:19 volumio firststart.sh[2278]: Creating /boot/userconfig.txt Mar 28 01:54:19 volumio firststart.sh[2278]: Removing default SSH host keys Mar 28 01:54:19 volumio systemd[1]: Startup finished in 3.772s (kernel) + 13.447s (userspace) = 17.219s. Mar 28 01:54:19 volumio firststart.sh[2317]: removed '/etc/ssh/ssh_host_ecdsa_key' Mar 28 01:54:19 volumio firststart.sh[2317]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Mar 28 01:54:19 volumio firststart.sh[2317]: removed '/etc/ssh/ssh_host_ed25519_key' Mar 28 01:54:19 volumio firststart.sh[2317]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Mar 28 01:54:19 volumio firststart.sh[2317]: removed '/etc/ssh/ssh_host_rsa_key' Mar 28 01:54:19 volumio firststart.sh[2317]: removed '/etc/ssh/ssh_host_rsa_key.pub' Mar 28 01:54:19 volumio firststart.sh[2278]: Generating SSH host keys Mar 28 01:54:19 volumio demo[2312]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:20 volumio demo[2321]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:20 volumio demo[2323]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:21 volumio demo[2325]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:21 volumio firststart.sh[2318]: debconf: unable to initialize frontend: Dialog Mar 28 01:54:21 volumio firststart.sh[2318]: debconf: (TERM is not set, so the dialog frontend is not usable.) Mar 28 01:54:21 volumio firststart.sh[2318]: debconf: falling back to frontend: Readline Mar 28 01:54:21 volumio firststart.sh[2318]: debconf: unable to initialize frontend: Readline Mar 28 01:54:21 volumio firststart.sh[2318]: debconf: (This frontend requires a controlling tty.) Mar 28 01:54:21 volumio firststart.sh[2318]: debconf: falling back to frontend: Teletype Mar 28 01:54:22 volumio demo[2341]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:22 volumio demo[2350]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:22 volumio firststart.sh[2355]: /usr/sbin/policy-rc.d returned 101, not running 'stop rescue-ssh.target' Mar 28 01:54:23 volumio demo[2377]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Mar 28 01:54:23 volumio socketio[2279]: IP is 127.0.0.1 Mar 28 01:54:23 volumio socketio[2279]: Port is 55390 Mar 28 01:54:23 volumio socketio[2279]: 8 Mar 28 01:54:23 volumio socketio[2279]: [2023-03-28 01:54:23] [info] asio async_connect error: asio.system:111 (Connection refused) Mar 28 01:54:23 volumio socketio[2279]: [2023-03-28 01:54:23] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Mar 28 01:54:23 volumio socketio[2279]: [2023-03-28 01:54:23] [error] handle_connect error: Connection refused Mar 28 01:54:26 volumio volumio[2277]: info: ------------------------------------------- Mar 28 01:54:26 volumio volumio[2277]: info: ----- Volumio2 ---- Mar 28 01:54:26 volumio volumio[2277]: info: ------------------------------------------- Mar 28 01:54:26 volumio volumio[2277]: info: ----- System startup ---- Mar 28 01:54:26 volumio volumio[2277]: info: ------------------------------------------- Mar 28 01:54:27 volumio firststart.sh[2364]: Creating SSH2 RSA key; this may take some time ... Mar 28 01:54:27 volumio firststart.sh[2450]: 3072 SHA256:K+Ji4G9YsgzwW62FgXvbTda0VDmS/cc4DeyJHXUPd74 root@volumio (RSA) Mar 28 01:54:28 volumio firststart.sh[2364]: Creating SSH2 DSA key; this may take some time ... Mar 28 01:54:28 volumio firststart.sh[2455]: 1024 SHA256:jZz1f+VSZNNY4HBKtevLSNJftWZLyRlREeIUmiXuky0 root@volumio (DSA) Mar 28 01:54:28 volumio firststart.sh[2364]: Creating SSH2 ECDSA key; this may take some time ... Mar 28 01:54:28 volumio firststart.sh[2460]: 256 SHA256:upwvJH/zLHuI6Sb5kuuwLuUP0QAns71cfPhEvGjzQRU root@volumio (ECDSA) Mar 28 01:54:29 volumio volumio[2277]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Mar 28 01:54:29 volumio volumio[2277]: info: Configured plugin music_service/youtube2 cannot be loaded. Removing from configuration Mar 28 01:54:29 volumio volumio[2277]: info: Plugin folders cleanup Mar 28 01:54:29 volumio volumio[2277]: info: Scanning into folder /volumio/app/plugins/ Mar 28 01:54:29 volumio volumio[2277]: info: Scanning category audio_interface Mar 28 01:54:29 volumio volumio[2277]: info: Scanning category miscellanea Mar 28 01:54:29 volumio volumio[2277]: info: Scanning category music_service Mar 28 01:54:29 volumio volumio[2277]: info: Scanning category plugins.json Mar 28 01:54:29 volumio volumio[2277]: info: Scanning category system_controller Mar 28 01:54:29 volumio volumio[2277]: info: Scanning category user_interface Mar 28 01:54:29 volumio volumio[2277]: info: Scanning into folder /data/plugins/ Mar 28 01:54:29 volumio volumio[2277]: info: Plugin folders cleanup completed Mar 28 01:54:29 volumio volumio[2277]: info: ------------------------------------------- Mar 28 01:54:29 volumio volumio[2277]: info: ----- Core plugins startup ---- Mar 28 01:54:29 volumio volumio[2277]: info: ------------------------------------------- Mar 28 01:54:29 volumio volumio[2277]: info: Loading plugins from folder /volumio/app/plugins/ Mar 28 01:54:29 volumio volumio[2277]: info: Adding plugin upnp to MyMusic Plugins Mar 28 01:54:29 volumio volumio[2277]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 28 01:54:29 volumio volumio[2277]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 28 01:54:29 volumio volumio[2277]: info: Loading plugins from folder /data/plugins/ Mar 28 01:54:29 volumio volumio[2277]: info: Loading plugin "system"... Mar 28 01:54:30 volumio volumio[2277]: No id defined. Creating one Mar 28 01:54:30 volumio volumio[2277]: info: Loading plugin "appearance"... Mar 28 01:54:31 volumio socketio[2279]: [2023-03-28 01:54:31] [connect] Successful connection Mar 28 01:54:32 volumio systemd[1]: Reloading. Mar 28 01:54:34 volumio firststart.sh[2493]: /usr/sbin/policy-rc.d returned 101, not running 'start rescue-ssh.target' Mar 28 01:54:34 volumio systemd[1]: Reloading. Mar 28 01:54:35 volumio kernel: usb0-vbus: disabling Mar 28 01:54:36 volumio firststart.sh[2523]: invoke-rc.d: policy-rc.d denied execution of restart. Mar 28 01:54:37 volumio firststart.sh[2278]: Disabling firststart service Mar 28 01:54:37 volumio firststart.sh[2535]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Mar 28 01:54:37 volumio systemd[1]: Reloading. Mar 28 01:54:39 volumio firststart.sh[2278]: Finalizing Mar 28 01:54:39 volumio systemd[1]: firststart.service: Succeeded. Mar 28 01:54:41 volumio volumio[2277]: info: Loading plugin "network"... Mar 28 01:54:41 volumio volumio[2277]: info: Refreshing Cached IP Addresses Mar 28 01:54:41 volumio sudo[2551]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:41 volumio volumio[2277]: info: Loading plugin "services"... Mar 28 01:54:41 volumio sudo[2553]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:41 volumio volumio[2277]: info: Plugin sources is not enabled Mar 28 01:54:41 volumio sudo[2553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 01:54:41 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 01:54:41 volumio volumio[2277]: info: Loading plugin "alsa_controller"... Mar 28 01:54:41 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:41 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:41 volumio sudo[2555]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:41 volumio sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 28 01:54:41 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:41 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:41 volumio sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:42 volumio sudo[2555]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:42 volumio volumio[2277]: info: Loading plugin "wizard"... Mar 28 01:54:42 volumio volumio[2277]: info: Loading plugin "volumio_command_line_client"... Mar 28 01:54:42 volumio volumio[2277]: info: Loading plugin "upnp"... Mar 28 01:54:42 volumio volumio[2277]: info: [1679939682208] Starting Upmpd Daemon Mar 28 01:54:42 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 01:54:42 volumio volumio[2277]: info: Loading plugin "my_music"... Mar 28 01:54:42 volumio volumio[2277]: info: Loading plugin "mpd"... Mar 28 01:54:43 volumio volumio[2277]: info: Creating MPD Configuration file Mar 28 01:54:43 volumio sudo[2587]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:43 volumio volumio[2277]: info: Loading plugin "upnp_browser"... Mar 28 01:54:43 volumio sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 01:54:43 volumio sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:43 volumio sudo[2590]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:43 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:43 volumio volumio[2277]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Release/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: error: Stack trace: Error: Could not locate the bindings file. Tried: Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Release/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Mar 28 01:54:43 volumio volumio[2277]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Mar 28 01:54:43 volumio volumio[2277]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Mar 28 01:54:43 volumio volumio[2277]: at Module._compile (internal/modules/cjs/loader.js:778:30) Mar 28 01:54:43 volumio volumio[2277]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Mar 28 01:54:43 volumio volumio[2277]: at Module.load (internal/modules/cjs/loader.js:653:32) Mar 28 01:54:43 volumio volumio[2277]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Mar 28 01:54:43 volumio volumio[2277]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Mar 28 01:54:43 volumio volumio[2277]: at Module.require (internal/modules/cjs/loader.js:692:17) Mar 28 01:54:43 volumio volumio[2277]: at require (internal/modules/cjs/helpers.js:25:18) Mar 28 01:54:43 volumio volumio[2277]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Mar 28 01:54:43 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:43 volumio volumio[2277]: info: Loading plugin "networkfs"... Mar 28 01:54:43 volumio sudo[2587]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:43 volumio sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 01:54:43 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:43 volumio systemd[1]: Stopping Music Player Daemon... Mar 28 01:54:43 volumio systemd[1]: mpd.service: Succeeded. Mar 28 01:54:43 volumio systemd[1]: Stopped Music Player Daemon. Mar 28 01:54:43 volumio systemd[1]: Starting Music Player Daemon... Mar 28 01:54:45 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:45 volumio volumio[2277]: error: The plugin system_controller/networkfs failed to load, setting it to stopped. Error: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Mar 28 01:54:45 volumio volumio[2277]: error: Stack trace: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Mar 28 01:54:45 volumio volumio[2277]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Mar 28 01:54:45 volumio volumio[2277]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Mar 28 01:54:45 volumio volumio[2277]: at Module.require (internal/modules/cjs/loader.js:692:17) Mar 28 01:54:45 volumio volumio[2277]: at require (internal/modules/cjs/helpers.js:25:18) Mar 28 01:54:45 volumio volumio[2277]: at Object. (/volumio/node_modules/udev/udev.js:4:12) Mar 28 01:54:45 volumio volumio[2277]: at Module._compile (internal/modules/cjs/loader.js:778:30) Mar 28 01:54:45 volumio volumio[2277]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Mar 28 01:54:45 volumio volumio[2277]: at Module.load (internal/modules/cjs/loader.js:653:32) Mar 28 01:54:45 volumio volumio[2277]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Mar 28 01:54:45 volumio volumio[2277]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Mar 28 01:54:45 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:45 volumio volumio[2277]: info: Loading plugin "alarm-clock"... Mar 28 01:54:45 volumio mpd[2603]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Mar 28 01:54:45 volumio mpd[2603]: config_file: config parameter "buffer_before_play" on line 85 is deprecated Mar 28 01:54:45 volumio mpd[2603]: config_file: config parameter "id3v1_encoding" on line 99 is deprecated Mar 28 01:54:45 volumio mpd[2603]: Mar 28 01:54 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 01:54:45 volumio mpd[2603]: Mar 28 01:54 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 28 01:54:45 volumio mpd[2603]: Mar 28 01:54 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 28 01:54:45 volumio systemd[1]: Started Music Player Daemon. Mar 28 01:54:45 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:45 volumio volumio[2277]: info: Loading plugin "airplay_emulation"... Mar 28 01:54:45 volumio volumio[2277]: info: Starting Shairport Sync Mar 28 01:54:45 volumio volumio[2277]: info: Loading plugin "last_100"... Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "webradio"... Mar 28 01:54:46 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:46 volumio volumio[2277]: error: The plugin music_service/webradio failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Release/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: error: Stack trace: Error: Could not locate the bindings file. Tried: Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/Release/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Mar 28 01:54:46 volumio volumio[2277]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Mar 28 01:54:46 volumio volumio[2277]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Mar 28 01:54:46 volumio volumio[2277]: at Module._compile (internal/modules/cjs/loader.js:778:30) Mar 28 01:54:46 volumio volumio[2277]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Mar 28 01:54:46 volumio volumio[2277]: at Module.load (internal/modules/cjs/loader.js:653:32) Mar 28 01:54:46 volumio volumio[2277]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Mar 28 01:54:46 volumio volumio[2277]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Mar 28 01:54:46 volumio volumio[2277]: at Module.require (internal/modules/cjs/loader.js:692:17) Mar 28 01:54:46 volumio volumio[2277]: at require (internal/modules/cjs/helpers.js:25:18) Mar 28 01:54:46 volumio volumio[2277]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Mar 28 01:54:46 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "i2s_dacs"... Mar 28 01:54:46 volumio volumio[2277]: info: I2S DAC not set, start Auto-detection Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "volumiodiscovery"... Mar 28 01:54:46 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:46 volumio volumio[2277]: error: The plugin system_controller/volumiodiscovery failed to load, setting it to stopped. Error: Error: Cannot find module '../build/Release/dns_sd_bindings' Mar 28 01:54:46 volumio volumio[2277]: error: Stack trace: Error: Cannot find module '../build/Release/dns_sd_bindings' Mar 28 01:54:46 volumio volumio[2277]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Mar 28 01:54:46 volumio volumio[2277]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Mar 28 01:54:46 volumio volumio[2277]: at Module.require (internal/modules/cjs/loader.js:692:17) Mar 28 01:54:46 volumio volumio[2277]: at require (internal/modules/cjs/helpers.js:25:18) Mar 28 01:54:46 volumio volumio[2277]: at Object. (/volumio/node_modules/mdns/lib/dns_sd.js:32:22) Mar 28 01:54:46 volumio volumio[2277]: at Module._compile (internal/modules/cjs/loader.js:778:30) Mar 28 01:54:46 volumio volumio[2277]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Mar 28 01:54:46 volumio volumio[2277]: at Module.load (internal/modules/cjs/loader.js:653:32) Mar 28 01:54:46 volumio volumio[2277]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Mar 28 01:54:46 volumio volumio[2277]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Mar 28 01:54:46 volumio volumio[2277]: error: !!!! WARNING !!!! Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "outputs"... Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "albumart"... Mar 28 01:54:46 volumio volumio[2277]: info: Plugin chromecast is not enabled Mar 28 01:54:46 volumio volumio[2277]: info: Plugin example_plugin is not enabled Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "inputs"... Mar 28 01:54:46 volumio volumio[2277]: info: Loading plugin "pandora"... Mar 28 01:54:47 volumio volumio[2277]: info: Loading plugin "podcast"... Mar 28 01:54:47 volumio socketio[2279]: [2023-03-28 01:54:47] [connect] Successful connection Mar 28 01:54:47 volumio nmbd[2230]: [2023/03/28 01:54:47.741125, 0] ../../source3/nmbd/nmbd_packets.c:1399(validate_nmb_packet) Mar 28 01:54:47 volumio nmbd[2230]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Mar 28 01:54:47 volumio nmbd[2230]: [2023/03/28 01:54:47.819355, 0] ../../source3/nmbd/nmbd_packets.c:1399(validate_nmb_packet) Mar 28 01:54:47 volumio nmbd[2230]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Mar 28 01:54:47 volumio nmbd[2230]: [2023/03/28 01:54:47.820450, 0] ../../source3/nmbd/nmbd_packets.c:1399(validate_nmb_packet) Mar 28 01:54:47 volumio nmbd[2230]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Mar 28 01:54:48 volumio volumio[2277]: info: ControllerPodcast::constructor Mar 28 01:54:48 volumio volumio[2277]: info: Loading plugin "qobuz"... Mar 28 01:54:48 volumio volumio[2277]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Mar 28 01:54:48 volumio volumio[2277]: info: Loading plugin "tidal"... Mar 28 01:54:49 volumio volumio[2277]: info: [1679939689522] ControllerTidalPlugin::constructor Mar 28 01:54:49 volumio volumio[2277]: info: [1679939689525] ControllerTidalPlugin::onVolumioStart Mar 28 01:54:49 volumio volumio[2277]: info: Loading plugin "volspotconnect2"... Mar 28 01:54:51 volumio volumio[2277]: [SpotifyConnect2] Creating VLS config file Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:51 volumio volumio[2277]: [SpotifyConnect2] <> or Mixer found, using softvol Mar 28 01:54:51 volumio volumio[2277]: info: Loading plugin "updater_comm"... Mar 28 01:54:51 volumio volumio[2277]: info: Plugin mpdemulation is not enabled Mar 28 01:54:51 volumio volumio[2277]: info: Loading plugin "rest_api"... Mar 28 01:54:51 volumio volumio[2277]: info: Loading plugin "websocket"... Mar 28 01:54:51 volumio volumio[2277]: info: ___________ START PLUGINS ___________ Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:51 volumio volumio[2277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 01:54:51 volumio volumio[2277]: info: [1679939691947] CoreMusicLibrary::Adding element Last_100 Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 01:54:51 volumio volumio[2277]: info: [1679939691975] CoreMusicLibrary::Adding element Pandora Mar 28 01:54:51 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 01:54:51 volumio volumio[2277]: info: [1679939691981] ControllerPandora::initializeMQTT Mar 28 01:54:51 volumio volumio[2277]: info: [1679939691983] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 01:54:52 volumio volumio[2277]: info: [1679939692010] CoreMusicLibrary::Adding element Podcast Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 01:54:52 volumio volumio[2277]: info: [1679939692019] CoreMusicLibrary::Adding element Qobuz Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 01:54:52 volumio volumio[2277]: info: [1679939692041] ControllerTidalPlugin::onStart Mar 28 01:54:52 volumio volumio[2277]: info: [1679939692052] ControllerTidalPlugin::addToBrowseSources Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 01:54:52 volumio volumio[2277]: info: [1679939692054] CoreMusicLibrary::Adding element Tidal Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 01:54:52 volumio volumio[2277]: Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Mar 28 01:54:52 volumio volumio[2277]: Could not load session from /data/configuration/music_service/tidal/tidal-session-oauth.json: Error: ENOENT: no such file or directory, open '/data/configuration/music_service/tidal/tidal-session-oauth.json' Mar 28 01:54:52 volumio volumio[2277]: [SpotifyConnect2] Creating VLS config file Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: [SpotifyConnect2] <> or Mixer found, using softvol Mar 28 01:54:52 volumio volumio[2277]: [SpotifyConnect2] Starting metadata listener Mar 28 01:54:52 volumio sudo[2618]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:52 volumio sudo[2618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Mar 28 01:54:52 volumio volumio[2277]: info: Loading i18n strings for locale en Mar 28 01:54:52 volumio volumio[2277]: Updating browse sources language Mar 28 01:54:52 volumio volumio[2277]: Cannot find translation for sourcePodcast Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 01:54:52 volumio sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::initPlayerControls Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:52 volumio systemd[1]: Configuration file /etc/systemd/system/volspotconnect2.service is marked executable. Please remove executable permission bits. Proceeding anyway. Mar 28 01:54:52 volumio systemd[1]: Started Volspotconnect2 Daemon. Mar 28 01:54:52 volumio sudo[2618]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: info: BOOT COMPLETED Mar 28 01:54:52 volumio volumio[2277]: [Metrics] CommandRouter: 26s 26.28ms Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 01:54:52 volumio volumio[2277]: ALSA lib pcm.c:2642:(snd_pcm_open_noupdate) Unknown PCM softvolume Mar 28 01:54:52 volumio volumio[2277]: aplay: main:852: audio open error: No such file or directory Mar 28 01:54:52 volumio volumio[2277]: Cannot play startup sound Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::Close All Modals sent Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::Close All Modals sent Mar 28 01:54:52 volumio volumio[2277]: Express server listening on port 3000 Mar 28 01:54:52 volumio volumio[2277]: [Metrics] WebUI: 28s 416.95ms Mar 28 01:54:52 volumio volumio[2628]: vollibrespot v0.2.4 61563b7 2021-07-22 (librespot 08d8bcc 2020-10-07) -- Built On 2021-07-22 Mar 28 01:54:52 volumio volumio[2628]: Reading Config from "volspotify.toml" Mar 28 01:54:52 volumio volumio[2628]: [Vollibrespot] : Using Alsa backend with device: hw:1 Mar 28 01:54:52 volumio volumio[2277]: info: [1679939692593] ControllerPandora::validateAndSetAccountOptions Mar 28 01:54:52 volumio volumio[2277]: error: [1679939692596] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Mar 28 01:54:52 volumio volumio[2277]: info: CoreStateMachine::resetVolumioState Mar 28 01:54:52 volumio volumio[2277]: info: CoreStateMachine::getcurrentVolume Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 01:54:52 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 01:54:52 volumio sudo[2643]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:52 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 01:54:52 volumio sudo[2647]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:52 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:52 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 01:54:52 volumio volumio[2277]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 28 01:54:52 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:52 volumio volumio[2277]: info: Cannot read play queue from file Mar 28 01:54:52 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:53 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:53 volumio volumio[2277]: info: CoreStateMachine::pushState Mar 28 01:54:53 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 01:54:53 volumio volumio[2277]: info: CoreStateMachine::updateTrackBlock Mar 28 01:54:53 volumio volumio[2277]: info: CorePlayQueue::getTrackBlock Mar 28 01:54:53 volumio volumio[2277]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 01:54:53 volumio volumio[2277]: [SpotifyConnect2] Vollibrespot Daemon service started! Mar 28 01:54:53 volumio volumio[2277]: [Metrics] SpotifyConnect: 1s 305.29ms Mar 28 01:54:53 volumio volumio[2277]: info: MPD Permissions set Mar 28 01:54:53 volumio volumio[2277]: info: Permissions for /etc/hostapd/hostapd.conf Mar 28 01:54:53 volumio sudo[2678]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:53 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 01:54:53 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 01:54:53 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 01:54:53 volumio sudo[2685]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:53 volumio sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 28 01:54:53 volumio sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:53 volumio systemd[1]: Starting Wireless Services... Mar 28 01:54:54 volumio sudo[2692]: pam_unix(sudo:account): account root has password changed in future Mar 28 01:54:54 volumio sudo[2692]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Mar 28 01:54:54 volumio sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:54 volumio volumio[2671]: Forking 1 albumart workers Mar 28 01:54:54 volumio wifistart.sh[2697]: command failed: No such device (-19) Mar 28 01:54:54 volumio sudo[2692]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:54 volumio volumio[2277]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Mar 28 01:54:54 volumio volumio[2277]: sudo: /usr/sbin/i2cdetect: command not found Mar 28 01:54:54 volumio volumio[2277]: info: CoreStateMachine::pushState Mar 28 01:54:54 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 01:54:54 volumio socketio[2279]: command_len 9 Mar 28 01:54:54 volumio socketio[2279]: getQueue Mar 28 01:54:54 volumio socketio[2279]: Mar 28 01:54:54 volumio volumio[2277]: info: Mar 28 01:54:54 volumio volumio[2277]: ---------------------------- Client requests Volume 100 Mar 28 01:54:54 volumio volumio[2277]: info: VolumeController::SetAlsaVolume100 Mar 28 01:54:54 volumio volumio[2277]: info: CoreStateMachine::pushState Mar 28 01:54:54 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 01:54:55 volumio volumio[2277]: sendCommand: Command { name: 'playlistinfo', args: [] } Mar 28 01:54:55 volumio volumio[2277]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Mar 28 01:54:55 volumio volumio[2277]: sendCommand: Command { name: 'search album ""', args: [] } Mar 28 01:54:55 volumio volumio[2277]: info: MPD running with PID2603 Mar 28 01:54:55 volumio volumio[2277]: ,establishing connection Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 01:54:55 volumio volumio[2277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 01:54:55 volumio volumio[2277]: error: updateQueue error: null Mar 28 01:54:55 volumio volumio[2277]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 28 01:54:56 volumio volumio[2277]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Mar 28 01:54:56 volumio volumio[2277]: info: Setting volume on startup at 100 Mar 28 01:54:56 volumio volumio[2277]: sendCommand: Command { name: 'playlistinfo', args: [] } Mar 28 01:54:56 volumio volumio[2277]: error: updateQueue error: null Mar 28 01:54:56 volumio volumio[2277]: info: Starting Shairport Sync Mar 28 01:54:56 volumio sudo[2727]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:56 volumio sudo[2727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 01:54:57 volumio sudo[2729]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:54:57 volumio sudo[2729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 28 01:54:57 volumio sudo[2727]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:57 volumio sudo[2729]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 01:54:57 volumio wireless.js[2709]: WIRELESS: No wireless interface, exiting Mar 28 01:54:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 01:54:57 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Mar 28 01:54:57 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Mar 28 01:54:57 volumio systemd[1]: Failed to start Wireless Services. Mar 28 01:54:57 volumio sudo[2685]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:57 volumio sudo[2727]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:57 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 28 01:54:57 volumio sudo[2729]: pam_unix(sudo:session): session closed for user root Mar 28 01:54:58 volumio volumio[2277]: info: Upmpdcli Daemon Started Mar 28 01:54:58 volumio volumio[2277]: info: Shairport-Sync Started Mar 28 01:54:58 volumio volumio[2277]: Error adding Membership: Error: addMembership EINVAL Mar 28 01:54:58 volumio volumio[2277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 01:54:58 volumio volumio[2277]: TypeError: f.split(...)[0].capitalize is not a function Mar 28 01:54:58 volumio volumio[2277]: at /volumio/app/plugins/miscellanea/appearance/index.js:198:56 Mar 28 01:54:58 volumio volumio[2277]: at Array.forEach () Mar 28 01:54:58 volumio volumio[2277]: at /volumio/app/plugins/miscellanea/appearance/index.js:196:11 Mar 28 01:54:58 volumio volumio[2277]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Mar 28 01:54:58 volumio volumio[2277]: at FSReqWrap.args [as oncomplete] (fs.js:140:20) Mar 28 01:54:58 volumio volumio[2277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 01:54:59 volumio volumio[2701]: Starting albumart workers Mar 28 01:55:01 volumio sudo[2796]: pam_unix(sudo:account): account volumio has password changed in future Mar 28 01:55:01 volumio sudo[2796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-03-28 01:54 Mar 28 01:55:01 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7"