-- Logs begin at Wed 2023-01-25 16:43:57 UTC, end at Wed 2023-01-25 16:44:53 UTC. -- Jan 25 16:43:57 volumio69 kernel: Booting Linux on physical CPU 0x0 Jan 25 16:43:57 volumio69 kernel: Linux version 5.10.92-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:36:39 GMT 2022 Jan 25 16:43:57 volumio69 kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Jan 25 16:43:57 volumio69 kernel: CPU: div instructions available: patching division code Jan 25 16:43:57 volumio69 kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Jan 25 16:43:57 volumio69 kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Jan 25 16:43:57 volumio69 kernel: random: fast init done Jan 25 16:43:57 volumio69 kernel: Memory policy: Data cache writealloc Jan 25 16:43:57 volumio69 kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB Jan 25 16:43:57 volumio69 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jan 25 16:43:57 volumio69 kernel: Zone ranges: Jan 25 16:43:57 volumio69 kernel: DMA [mem 0x0000000000000000-0x000000003dffffff] Jan 25 16:43:57 volumio69 kernel: Normal empty Jan 25 16:43:57 volumio69 kernel: Movable zone start for each node Jan 25 16:43:57 volumio69 kernel: Early memory node ranges Jan 25 16:43:57 volumio69 kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Jan 25 16:43:57 volumio69 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff] Jan 25 16:43:57 volumio69 kernel: On node 0 totalpages: 253952 Jan 25 16:43:57 volumio69 kernel: DMA zone: 2232 pages used for memmap Jan 25 16:43:57 volumio69 kernel: DMA zone: 0 pages reserved Jan 25 16:43:57 volumio69 kernel: DMA zone: 253952 pages, LIFO batch:63 Jan 25 16:43:57 volumio69 kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Jan 25 16:43:57 volumio69 kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Jan 25 16:43:57 volumio69 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jan 25 16:43:57 volumio69 kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Jan 25 16:43:57 volumio69 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jan 25 16:43:57 volumio69 kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Jan 25 16:43:57 volumio69 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Jan 25 16:43:57 volumio69 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 25 16:43:57 volumio69 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 25 16:43:57 volumio69 kernel: Memory: 910432K/1015808K available (10240K kernel code, 1312K rwdata, 2952K rodata, 1024K init, 862K bss, 39840K reserved, 65536K cma-reserved) Jan 25 16:43:57 volumio69 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jan 25 16:43:57 volumio69 kernel: ftrace: allocating 32087 entries in 95 pages Jan 25 16:43:57 volumio69 kernel: ftrace: allocated 95 pages with 6 groups Jan 25 16:43:57 volumio69 kernel: rcu: Hierarchical RCU implementation. Jan 25 16:43:57 volumio69 kernel: Rude variant of Tasks RCU enabled. Jan 25 16:43:57 volumio69 kernel: Tracing variant of Tasks RCU enabled. Jan 25 16:43:57 volumio69 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Jan 25 16:43:57 volumio69 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Jan 25 16:43:57 volumio69 kernel: random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1 Jan 25 16:43:57 volumio69 kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Jan 25 16:43:57 volumio69 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Jan 25 16:43:57 volumio69 kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Jan 25 16:43:57 volumio69 kernel: Switching to timer-based delay loop, resolution 52ns Jan 25 16:43:57 volumio69 kernel: Console: colour dummy device 80x30 Jan 25 16:43:57 volumio69 kernel: printk: console [tty1] enabled Jan 25 16:43:57 volumio69 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Jan 25 16:43:57 volumio69 kernel: pid_max: default: 32768 minimum: 301 Jan 25 16:43:57 volumio69 kernel: LSM: Security Framework initializing Jan 25 16:43:57 volumio69 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Jan 25 16:43:57 volumio69 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Jan 25 16:43:57 volumio69 kernel: cgroup: Disabling memory control group subsystem Jan 25 16:43:57 volumio69 kernel: CPU: Testing write buffer coherency: ok Jan 25 16:43:57 volumio69 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Jan 25 16:43:57 volumio69 kernel: Setting up static identity map for 0x100000 - 0x10003c Jan 25 16:43:57 volumio69 kernel: rcu: Hierarchical SRCU implementation. Jan 25 16:43:57 volumio69 kernel: smp: Bringing up secondary CPUs ... Jan 25 16:43:57 volumio69 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Jan 25 16:43:57 volumio69 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Jan 25 16:43:57 volumio69 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Jan 25 16:43:57 volumio69 kernel: smp: Brought up 1 node, 4 CPUs Jan 25 16:43:57 volumio69 kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Jan 25 16:43:57 volumio69 kernel: CPU: All CPU(s) started in HYP mode. Jan 25 16:43:57 volumio69 kernel: CPU: Virtualization extensions available. Jan 25 16:43:57 volumio69 kernel: devtmpfs: initialized Jan 25 16:43:57 volumio69 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Jan 25 16:43:57 volumio69 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Jan 25 16:43:57 volumio69 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jan 25 16:43:57 volumio69 kernel: pinctrl core: initialized pinctrl subsystem Jan 25 16:43:57 volumio69 kernel: NET: Registered protocol family 16 Jan 25 16:43:57 volumio69 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Jan 25 16:43:57 volumio69 kernel: audit: initializing netlink subsys (disabled) Jan 25 16:43:57 volumio69 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 25 16:43:57 volumio69 kernel: audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1 Jan 25 16:43:57 volumio69 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Jan 25 16:43:57 volumio69 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Jan 25 16:43:57 volumio69 kernel: Serial: AMBA PL011 UART driver Jan 25 16:43:57 volumio69 kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Jan 25 16:43:57 volumio69 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:58:22, variant start Jan 25 16:43:57 volumio69 kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Jan 25 16:43:57 volumio69 kernel: Kprobes globally optimized Jan 25 16:43:57 volumio69 kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Jan 25 16:43:57 volumio69 kernel: SCSI subsystem initialized Jan 25 16:43:57 volumio69 kernel: usbcore: registered new interface driver usbfs Jan 25 16:43:57 volumio69 kernel: usbcore: registered new interface driver hub Jan 25 16:43:57 volumio69 kernel: usbcore: registered new device driver usb Jan 25 16:43:57 volumio69 kernel: clocksource: Switched to clocksource arch_sys_counter Jan 25 16:43:57 volumio69 kernel: VFS: Disk quotas dquot_6.6.0 Jan 25 16:43:57 volumio69 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Jan 25 16:43:57 volumio69 kernel: FS-Cache: Loaded Jan 25 16:43:57 volumio69 kernel: CacheFiles: Loaded Jan 25 16:43:57 volumio69 kernel: NET: Registered protocol family 2 Jan 25 16:43:57 volumio69 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 25 16:43:57 volumio69 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Jan 25 16:43:57 volumio69 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Jan 25 16:43:57 volumio69 kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Jan 25 16:43:57 volumio69 kernel: TCP: Hash tables configured (established 8192 bind 8192) Jan 25 16:43:57 volumio69 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Jan 25 16:43:57 volumio69 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Jan 25 16:43:57 volumio69 kernel: NET: Registered protocol family 1 Jan 25 16:43:57 volumio69 kernel: RPC: Registered named UNIX socket transport module. Jan 25 16:43:57 volumio69 kernel: RPC: Registered udp transport module. Jan 25 16:43:57 volumio69 kernel: RPC: Registered tcp transport module. Jan 25 16:43:57 volumio69 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 25 16:43:57 volumio69 kernel: Trying to unpack rootfs image as initramfs... Jan 25 16:43:57 volumio69 kernel: Freeing initrd memory: 13028K Jan 25 16:43:57 volumio69 kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Jan 25 16:43:57 volumio69 kernel: Initialise system trusted keyrings Jan 25 16:43:57 volumio69 kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Jan 25 16:43:57 volumio69 kernel: zbud: loaded Jan 25 16:43:57 volumio69 kernel: FS-Cache: Netfs 'nfs' registered for caching Jan 25 16:43:57 volumio69 kernel: NFS: Registering the id_resolver key type Jan 25 16:43:57 volumio69 kernel: Key type id_resolver registered Jan 25 16:43:57 volumio69 kernel: Key type id_legacy registered Jan 25 16:43:57 volumio69 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jan 25 16:43:57 volumio69 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jan 25 16:43:57 volumio69 kernel: Key type asymmetric registered Jan 25 16:43:57 volumio69 kernel: Asymmetric key parser 'x509' registered Jan 25 16:43:57 volumio69 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jan 25 16:43:57 volumio69 kernel: io scheduler mq-deadline registered Jan 25 16:43:57 volumio69 kernel: io scheduler kyber registered Jan 25 16:43:57 volumio69 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jan 25 16:43:57 volumio69 kernel: Console: switching to colour frame buffer device 228x61 Jan 25 16:43:57 volumio69 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1824x984 Jan 25 16:43:57 volumio69 kernel: bcm2835-rng 3f104000.rng: hwrng registered Jan 25 16:43:57 volumio69 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 25 16:43:57 volumio69 kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Jan 25 16:43:57 volumio69 kernel: brd: module loaded Jan 25 16:43:57 volumio69 kernel: loop: module loaded Jan 25 16:43:57 volumio69 kernel: Loading iSCSI transport class v2.0-870. Jan 25 16:43:57 volumio69 kernel: libphy: Fixed MDIO Bus: probed Jan 25 16:43:57 volumio69 kernel: usbcore: registered new interface driver lan78xx Jan 25 16:43:57 volumio69 kernel: usbcore: registered new interface driver smsc95xx Jan 25 16:43:57 volumio69 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 25 16:43:57 volumio69 kernel: Core Release: 2.80a Jan 25 16:43:57 volumio69 kernel: Setting default values for core params Jan 25 16:43:57 volumio69 kernel: Finished setting default values for core params Jan 25 16:43:57 volumio69 kernel: Using Buffer DMA mode Jan 25 16:43:57 volumio69 kernel: Periodic Transfer Interrupt Enhancement - disabled Jan 25 16:43:57 volumio69 kernel: Multiprocessor Interrupt Enhancement - disabled Jan 25 16:43:57 volumio69 kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Jan 25 16:43:57 volumio69 kernel: Dedicated Tx FIFOs mode Jan 25 16:43:57 volumio69 kernel: Jan 25 16:43:57 volumio69 kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba104000 dma = 0xfa104000 len=9024 Jan 25 16:43:57 volumio69 kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Jan 25 16:43:57 volumio69 kernel: dwc_otg: Microframe scheduler enabled Jan 25 16:43:57 volumio69 kernel: Jan 25 16:43:57 volumio69 kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Jan 25 16:43:57 volumio69 kernel: Jan 25 16:43:57 volumio69 kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cc05c length 36 Jan 25 16:43:57 volumio69 kernel: Jan 25 16:43:57 volumio69 kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Jan 25 16:43:57 volumio69 kernel: dwc_otg 3f980000.usb: DWC OTG Controller Jan 25 16:43:57 volumio69 kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Jan 25 16:43:57 volumio69 kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Jan 25 16:43:57 volumio69 kernel: Init: Port Power? op_state=1 Jan 25 16:43:57 volumio69 kernel: Init: Power Port (0) Jan 25 16:43:57 volumio69 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Jan 25 16:43:57 volumio69 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 25 16:43:57 volumio69 kernel: usb usb1: Product: DWC OTG Controller Jan 25 16:43:57 volumio69 kernel: usb usb1: Manufacturer: Linux 5.10.92-v7+ dwc_otg_hcd Jan 25 16:43:57 volumio69 kernel: usb usb1: SerialNumber: 3f980000.usb Jan 25 16:43:57 volumio69 kernel: hub 1-0:1.0: USB hub found Jan 25 16:43:57 volumio69 kernel: hub 1-0:1.0: 1 port detected Jan 25 16:43:57 volumio69 kernel: dwc_otg: FIQ enabled Jan 25 16:43:57 volumio69 kernel: dwc_otg: NAK holdoff enabled Jan 25 16:43:57 volumio69 kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 25 16:43:57 volumio69 kernel: Module dwc_common_port init Jan 25 16:43:57 volumio69 kernel: usbcore: registered new interface driver usb-storage Jan 25 16:43:57 volumio69 kernel: mousedev: PS/2 mouse device common for all mice Jan 25 16:43:57 volumio69 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 25 16:43:57 volumio69 kernel: sdhci: Secure Digital Host Controller Interface driver Jan 25 16:43:57 volumio69 kernel: sdhci: Copyright(c) Pierre Ossman Jan 25 16:43:57 volumio69 kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Jan 25 16:43:57 volumio69 kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Jan 25 16:43:57 volumio69 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 25 16:43:57 volumio69 kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 25 16:43:57 volumio69 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 25 16:43:57 volumio69 kernel: usbcore: registered new interface driver usbhid Jan 25 16:43:57 volumio69 kernel: usbhid: USB HID core driver Jan 25 16:43:57 volumio69 kernel: Initializing XFRM netlink socket Jan 25 16:43:57 volumio69 kernel: NET: Registered protocol family 17 Jan 25 16:43:57 volumio69 kernel: Key type dns_resolver registered Jan 25 16:43:57 volumio69 kernel: Registering SWP/SWPB emulation handler Jan 25 16:43:57 volumio69 kernel: registered taskstats version 1 Jan 25 16:43:57 volumio69 kernel: Loading compiled-in X.509 certificates Jan 25 16:43:57 volumio69 kernel: Key type ._fscrypt registered Jan 25 16:43:57 volumio69 kernel: Key type .fscrypt registered Jan 25 16:43:57 volumio69 kernel: Key type fscrypt-provisioning registered Jan 25 16:43:57 volumio69 kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Jan 25 16:43:57 volumio69 kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Jan 25 16:43:57 volumio69 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 25 16:43:57 volumio69 kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 25 16:43:57 volumio69 kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Jan 25 16:43:57 volumio69 kernel: sdhost: log_buf @ (ptrval) (fa107000) Jan 25 16:43:57 volumio69 kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Jan 25 16:43:57 volumio69 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 25 16:43:57 volumio69 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 25 16:43:57 volumio69 kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Jan 25 16:43:57 volumio69 kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Jan 25 16:43:57 volumio69 kernel: of_cfs_init Jan 25 16:43:57 volumio69 kernel: of_cfs_init: OK Jan 25 16:43:57 volumio69 kernel: Indeed it is in host mode hprt0 = 00021501 Jan 25 16:43:57 volumio69 kernel: Freeing unused kernel memory: 1024K Jan 25 16:43:57 volumio69 kernel: Run /init as init process Jan 25 16:43:57 volumio69 kernel: with arguments: Jan 25 16:43:57 volumio69 kernel: /init Jan 25 16:43:57 volumio69 kernel: splash Jan 25 16:43:57 volumio69 kernel: with environment: Jan 25 16:43:57 volumio69 kernel: HOME=/ Jan 25 16:43:57 volumio69 kernel: TERM=linux Jan 25 16:43:57 volumio69 kernel: imgpart=/dev/mmcblk0p2 Jan 25 16:43:57 volumio69 kernel: imgfile=/volumio_current.sqsh Jan 25 16:43:57 volumio69 kernel: bootdelay=5 Jan 25 16:43:57 volumio69 kernel: mmc0: host does not support reading read-only switch, assuming write-enable Jan 25 16:43:57 volumio69 kernel: mmc0: new high speed SDHC card at address 59b4 Jan 25 16:43:57 volumio69 kernel: mmcblk0: mmc0:59b4 USD 15.0 GiB Jan 25 16:43:57 volumio69 kernel: mmcblk0: p1 p2 p3 Jan 25 16:43:57 volumio69 kernel: mmc1: new high speed SDIO card at address 0001 Jan 25 16:43:57 volumio69 kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Jan 25 16:43:57 volumio69 kernel: Indeed it is in host mode hprt0 = 00001101 Jan 25 16:43:57 volumio69 initramfs: Booting Volumio for BCM2835 Jan 25 16:43:57 volumio69 initramfs: This script mounts rootfs RO with an overlay RW layer. Jan 25 16:43:57 volumio69 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 25 16:43:57 volumio69 initramfs: IMGPART=/dev/mmcblk0p2 Jan 25 16:43:57 volumio69 initramfs: IMGFILE=/volumio_current.sqsh Jan 25 16:43:57 volumio69 initramfs: Boot delay (except first time) will be 5 seconds Jan 25 16:43:57 volumio69 initramfs: /dev/mmcblk0p2: Jan 25 16:43:57 volumio69 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jan 25 16:43:57 volumio69 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 25 16:43:57 volumio69 kernel: hub 1-1:1.0: USB hub found Jan 25 16:43:57 volumio69 kernel: hub 1-1:1.0: 5 ports detected Jan 25 16:43:57 volumio69 kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 25 16:43:57 volumio69 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jan 25 16:43:57 volumio69 initramfs: Doing a 5 second delay here to give kernel load a headstart Jan 25 16:43:57 volumio69 kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Jan 25 16:43:57 volumio69 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jan 25 16:43:57 volumio69 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 25 16:43:57 volumio69 kernel: smsc95xx v2.0.0 Jan 25 16:43:57 volumio69 kernel: libphy: smsc95xx-mdiobus: probed Jan 25 16:43:57 volumio69 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:f6:e8:68 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5: new high-speed USB device number 4 using dwc_otg Jan 25 16:43:57 volumio69 kernel: usb 1-1.5: New USB device found, idVendor=0409, idProduct=005a, bcdDevice= 1.00 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 25 16:43:57 volumio69 kernel: hub 1-1.5:1.0: USB hub found Jan 25 16:43:57 volumio69 kernel: hub 1-1.5:1.0: 4 ports detected Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.2: new high-speed USB device number 5 using dwc_otg Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.2: New USB device found, idVendor=0480, idProduct=0900, bcdDevice= 3.22 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.2: Product: External USB 3.0 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.2: Manufacturer: TOSHIBA Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.2: SerialNumber: 20210131028262F Jan 25 16:43:57 volumio69 kernel: usb-storage 1-1.5.2:1.0: USB Mass Storage device detected Jan 25 16:43:57 volumio69 kernel: scsi host0: usb-storage 1-1.5.2:1.0 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.3: new low-speed USB device number 6 using dwc_otg Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.3: New USB device found, idVendor=1c4f, idProduct=0055, bcdDevice= 1.10 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.3: Product: USB Keyboard Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.3: Manufacturer: SIGMACHIP Jan 25 16:43:57 volumio69 kernel: input: SIGMACHIP USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5.3/1-1.5.3:1.0/0003:1C4F:0055.0001/input/input0 Jan 25 16:43:57 volumio69 kernel: hid-generic 0003:1C4F:0055.0001: input,hidraw0: USB HID v1.10 Keyboard [SIGMACHIP USB Keyboard] on usb-3f980000.usb-1.5.3/input0 Jan 25 16:43:57 volumio69 kernel: input: SIGMACHIP USB Keyboard Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5.3/1-1.5.3:1.1/0003:1C4F:0055.0002/input/input1 Jan 25 16:43:57 volumio69 kernel: input: SIGMACHIP USB Keyboard System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5.3/1-1.5.3:1.1/0003:1C4F:0055.0002/input/input2 Jan 25 16:43:57 volumio69 kernel: hid-generic 0003:1C4F:0055.0002: input,hidraw1: USB HID v1.10 Device [SIGMACHIP USB Keyboard] on usb-3f980000.usb-1.5.3/input1 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.4: new low-speed USB device number 7 using dwc_otg Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.4: New USB device found, idVendor=045e, idProduct=0040, bcdDevice= 3.00 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.4: Product: Microsoft 3-Button Mouse with IntelliEye(TM) Jan 25 16:43:57 volumio69 kernel: usb 1-1.5.4: Manufacturer: Microsoft Jan 25 16:43:57 volumio69 kernel: input: Microsoft Microsoft 3-Button Mouse with IntelliEye(TM) as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5.4/1-1.5.4:1.0/0003:045E:0040.0003/input/input3 Jan 25 16:43:57 volumio69 kernel: hid-generic 0003:045E:0040.0003: input,hidraw2: USB HID v1.10 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye(TM)] on usb-3f980000.usb-1.5.4/input0 Jan 25 16:43:57 volumio69 kernel: scsi 0:0:0:0: Direct-Access TOSHIBA External USB 3.0 5438 PQ: 0 ANSI: 6 Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] 7814037164 512-byte logical blocks: (4.00 TB/3.64 TiB) Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] Write Protect is off Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jan 25 16:43:57 volumio69 initramfs: Checking for USB updates Jan 25 16:43:57 volumio69 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 25 16:43:57 volumio69 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 25 16:43:57 volumio69 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 25 16:43:57 volumio69 kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 25 16:43:57 volumio69 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jan 25 16:43:57 volumio69 initramfs: With Option: Jan 25 16:43:57 volumio69 initramfs: VOLUMIO_VERSION="3.396" Jan 25 16:43:57 volumio69 initramfs: Finish initramfs, continue booting Volumio Jan 25 16:43:57 volumio69 systemd[1]: System time before build time, advancing clock. Jan 25 16:43:57 volumio69 kernel: NET: Registered protocol family 10 Jan 25 16:43:57 volumio69 kernel: Segment Routing with IPv6 Jan 25 16:43:57 volumio69 systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Jan 25 16:43:57 volumio69 systemd[1]: Detected architecture arm. Jan 25 16:43:57 volumio69 systemd[1]: Set hostname to . Jan 25 16:43:57 volumio69 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 25 16:43:57 volumio69 kernel: sda: sda1 sda2 Jan 25 16:43:57 volumio69 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jan 25 16:43:57 volumio69 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 25 16:43:57 volumio69 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 25 16:43:57 volumio69 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 25 16:43:57 volumio69 systemd[1]: Created slice system-getty.slice. Jan 25 16:43:57 volumio69 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 25 16:43:57 volumio69 systemd[1]: Created slice User and Session Slice. Jan 25 16:43:57 volumio69 systemd[1]: Listening on udev Control Socket. Jan 25 16:43:57 volumio69 systemd[1]: Listening on Journal Socket. Jan 25 16:43:57 volumio69 systemd[1]: Mounting POSIX Message Queue File System... Jan 25 16:43:57 volumio69 kernel: i2c /dev entries driver Jan 25 16:43:57 volumio69 systemd-journald[198]: Journal started Jan 25 16:43:57 volumio69 systemd-journald[198]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 3.7M, max 30.0M, 26.2M free. Jan 25 16:43:57 volumio69 systemd-modules-load[191]: Inserted module 'i2c_dev' Jan 25 16:43:57 volumio69 fake-hwclock[190]: Wed Jan 25 16:43:57 UTC 2023 Jan 25 16:43:57 volumio69 systemd[1]: Started Create Static Device Nodes in /dev. Jan 25 16:43:57 volumio69 systemd[1]: Reached target Local File Systems (Pre). Jan 25 16:43:57 volumio69 systemd[1]: Mounting /var/spool/cups... Jan 25 16:43:57 volumio69 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 25 16:43:57 volumio69 systemd[1]: Mounting /tmp... Jan 25 16:43:57 volumio69 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 25 16:43:57 volumio69 systemd[1]: Mounting /var/log... Jan 25 16:43:57 volumio69 systemd[1]: Starting udev Kernel Device Manager... Jan 25 16:43:57 volumio69 systemd[1]: Mounted /var/spool/cups. Jan 25 16:43:57 volumio69 systemd[1]: Mounted /tmp. Jan 25 16:43:57 volumio69 systemd[1]: Mounted /var/log. Jan 25 16:43:57 volumio69 systemd[1]: Starting Flush Journal to Persistent Storage... Jan 25 16:43:57 volumio69 systemd[1]: Mounting /var/spool/cups/tmp... Jan 25 16:43:57 volumio69 systemd[1]: Started udev Coldplug all Devices. Jan 25 16:43:57 volumio69 systemd-udevd[217]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 25 16:43:57 volumio69 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 25 16:43:57 volumio69 systemd[1]: Mounted /var/spool/cups/tmp. Jan 25 16:43:57 volumio69 systemd-journald[198]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 3.7M, max 30.0M, 26.2M free. Jan 25 16:43:57 volumio69 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 25 16:43:57 volumio69 systemd[1]: Started Flush Journal to Persistent Storage. Jan 25 16:43:57 volumio69 systemd[1]: Started udev Kernel Device Manager. Jan 25 16:43:57 volumio69 systemd[1]: Starting Show Plymouth Boot Screen... Jan 25 16:43:57 volumio69 systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Jan 25 16:43:57 volumio69 systemd[1]: Started Show Plymouth Boot Screen. Jan 25 16:43:57 volumio69 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 25 16:43:57 volumio69 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 25 16:43:57 volumio69 systemd[1]: Reached target Paths. Jan 25 16:43:57 volumio69 systemd[1]: Reached target Local Encrypted Volumes. Jan 25 16:43:57 volumio69 kernel: mc: Linux media interface: v0.10 Jan 25 16:43:58 volumio69 kernel: videodev: Linux video capture interface: v2.00 Jan 25 16:43:58 volumio69 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 25 16:43:58 volumio69 kernel: [vc_sm_connected_init]: start Jan 25 16:43:58 volumio69 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: [vc_sm_connected_init]: installed successfully Jan 25 16:43:58 volumio69 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 25 16:43:58 volumio69 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 25 16:43:58 volumio69 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 25 16:43:58 volumio69 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 25 16:43:59 volumio69 systemd-udevd[227]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 25 16:43:59 volumio69 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 25 16:43:59 volumio69 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 25 16:43:59 volumio69 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 25 16:43:59 volumio69 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 25 16:43:59 volumio69 systemd-udevd[223]: Using default interface naming scheme 'v240'. Jan 25 16:43:59 volumio69 kernel: usbcore: registered new interface driver uas Jan 25 16:43:59 volumio69 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jan 25 16:43:59 volumio69 systemd-udevd[223]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 25 16:43:59 volumio69 kernel: random: crng init done Jan 25 16:43:59 volumio69 kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 25 16:43:59 volumio69 kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jan 25 16:43:59 volumio69 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 25 16:43:59 volumio69 kernel: usbcore: registered new interface driver brcmfmac Jan 25 16:43:59 volumio69 systemd[1]: Found device /dev/mmcblk0p1. Jan 25 16:43:59 volumio69 systemd-udevd[232]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 25 16:43:59 volumio69 systemd-udevd[226]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 25 16:43:59 volumio69 systemd-udevd[224]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 25 16:44:00 volumio69 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 25 16:44:00 volumio69 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 25 16:44:00 volumio69 kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Jan 25 16:44:00 volumio69 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd Jan 25 16:44:00 volumio69 systemd-udevd[231]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 25 16:44:00 volumio69 systemd-udevd[230]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 25 16:44:00 volumio69 systemd-udevd[224]: Using default interface naming scheme 'v240'. Jan 25 16:44:00 volumio69 systemd-udevd[224]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 25 16:44:01 volumio69 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 25 16:44:02 volumio69 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 25 16:44:02 volumio69 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 25 16:44:02 volumio69 systemd[1]: Started File System Check Daemon to report status. Jan 25 16:44:02 volumio69 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 25 16:44:02 volumio69 systemd-fsck[384]: fsck.fat 4.1 (2017-01-24) Jan 25 16:44:02 volumio69 systemd-fsck[384]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 25 16:44:02 volumio69 systemd-fsck[384]: Automatically removing dirty bit. Jan 25 16:44:02 volumio69 systemd-fsck[384]: Performing changes. Jan 25 16:44:02 volumio69 systemd-fsck[384]: /dev/mmcblk0p1: 297 files, 27486/46774 clusters Jan 25 16:44:02 volumio69 systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 25 16:44:02 volumio69 systemd[1]: Mounting /boot... Jan 25 16:44:02 volumio69 systemd[1]: Mounted /boot. Jan 25 16:44:02 volumio69 systemd[1]: Reached target Local File Systems. Jan 25 16:44:02 volumio69 systemd[1]: Started ifup for eth0. Jan 25 16:44:02 volumio69 systemd[1]: Starting Raise network interfaces... Jan 25 16:44:02 volumio69 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 25 16:44:02 volumio69 systemd[1]: Started ifup for wlan0. Jan 25 16:44:02 volumio69 systemd[1]: Starting Preprocess NFS configuration... Jan 25 16:44:02 volumio69 systemd[1]: Starting Create Volatile Files and Directories... Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 25 16:44:02 volumio69 systemd[1]: plymouth-read-write.service: Succeeded. Jan 25 16:44:02 volumio69 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 25 16:44:02 volumio69 systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Jan 25 16:44:02 volumio69 systemd[1]: nfs-config.service: Succeeded. Jan 25 16:44:02 volumio69 systemd[1]: Started Preprocess NFS configuration. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 25 16:44:02 volumio69 systemd[1]: Reached target NFS client services. Jan 25 16:44:02 volumio69 systemd[1]: Started Create Volatile Files and Directories. Jan 25 16:44:02 volumio69 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 25 16:44:03 volumio69 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 25 16:44:03 volumio69 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 25 16:44:03 volumio69 systemd[1]: Starting RPC bind portmap service... Jan 25 16:44:03 volumio69 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 25 16:44:03 volumio69 kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 25 16:44:03 volumio69 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 25 16:44:03 volumio69 systemd[1]: Reached target System Initialization. Jan 25 16:44:03 volumio69 systemd[1]: Listening on mpd.socket. Jan 25 16:44:03 volumio69 systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 25 16:44:03 volumio69 systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 25 16:44:03 volumio69 systemd[1]: apt-daily.timer: Not using persistent file timestamp Sat 2023-02-04 09:26:15 UTC as it is in the future. Jan 25 16:44:03 volumio69 systemd[1]: Started Daily apt download activities. Jan 25 16:44:03 volumio69 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sat 2023-02-04 06:57:15 UTC as it is in the future. Jan 25 16:44:03 volumio69 systemd[1]: Started Daily apt upgrade and clean activities. Jan 25 16:44:03 volumio69 systemd[1]: Reached target Timers. Jan 25 16:44:03 volumio69 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 25 16:44:03 volumio69 systemd[1]: Listening on triggerhappy.socket. Jan 25 16:44:03 volumio69 systemd[1]: Reached target Sockets. Jan 25 16:44:03 volumio69 systemd[1]: Reached target Basic System. Jan 25 16:44:03 volumio69 systemd[1]: Started D-Bus System Message Bus. Jan 25 16:44:03 volumio69 systemd[1]: Started Volumio Iptables Module. Jan 25 16:44:03 volumio69 systemd[1]: Started volumio-remote-updater.service. Jan 25 16:44:03 volumio69 systemd[1]: Started Manage Sound Card State (restore and store). Jan 25 16:44:03 volumio69 alsactl[490]: alsactl 1.1.8 daemon started Jan 25 16:44:03 volumio69 systemd[1]: Starting WPA supplicant... Jan 25 16:44:03 volumio69 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 25 16:44:03 volumio69 systemd[1]: Starting Save/Restore Sound Card State... Jan 25 16:44:03 volumio69 systemd[1]: Starting dhcpcd on all interfaces... Jan 25 16:44:03 volumio69 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 25 16:44:03 volumio69 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 25 16:44:03 volumio69 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 25 16:44:03 volumio69 systemd[1]: Starting triggerhappy global hotkey daemon... Jan 25 16:44:03 volumio69 systemd[1]: Starting Wireless Services... Jan 25 16:44:03 volumio69 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 25 16:44:03 volumio69 dhcpcd[496]: Not running dhcpcd because /etc/network/interfaces Jan 25 16:44:03 volumio69 dhcpcd[496]: defines some interfaces that will use a Jan 25 16:44:03 volumio69 dhcpcd[496]: DHCP client or static address Jan 25 16:44:03 volumio69 systemd[1]: Starting Login Service... Jan 25 16:44:03 volumio69 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 25 16:44:03 volumio69 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 25 16:44:03 volumio69 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 25 16:44:03 volumio69 systemd[1]: Failed to start dhcpcd on all interfaces. Jan 25 16:44:03 volumio69 thd[501]: Unable to parse trigger line: Jan 25 16:44:03 volumio69 thd[501]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 25 16:44:03 volumio69 thd[501]: Unable to parse trigger line: Jan 25 16:44:03 volumio69 sh[395]: wlan0=wlan0 Jan 25 16:44:04 volumio69 sh[390]: eth0: waiting for carrier Jan 25 16:44:04 volumio69 dhcpcd[438]: eth0: waiting for carrier Jan 25 16:44:04 volumio69 kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL) Jan 25 16:44:04 volumio69 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 25 16:44:04 volumio69 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jan 25 16:44:04 volumio69 kernel: uart-pl011 3f201000.serial: no DMA platform data Jan 25 16:44:04 volumio69 thd[501]: Found socket passed from systemd Jan 25 16:44:04 volumio69 avahi-daemon[505]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 25 16:44:04 volumio69 avahi-daemon[505]: Successfully dropped root privileges. Jan 25 16:44:04 volumio69 avahi-daemon[505]: avahi-daemon 0.7 starting up. Jan 25 16:44:04 volumio69 systemd[1]: Started triggerhappy global hotkey daemon. Jan 25 16:44:04 volumio69 systemd[1]: Started RPC bind portmap service. Jan 25 16:44:04 volumio69 systemd-logind[504]: Watching system buttons on /dev/input/event0 (SIGMACHIP USB Keyboard) Jan 25 16:44:04 volumio69 systemd-logind[504]: Watching system buttons on /dev/input/event1 (SIGMACHIP USB Keyboard Consumer Control) Jan 25 16:44:04 volumio69 systemd-logind[504]: Watching system buttons on /dev/input/event2 (SIGMACHIP USB Keyboard System Control) Jan 25 16:44:04 volumio69 avahi-daemon[505]: Successfully called chroot(). Jan 25 16:44:04 volumio69 avahi-daemon[505]: Successfully dropped remaining capabilities. Jan 25 16:44:04 volumio69 systemd[1]: Started Save/Restore Sound Card State. Jan 25 16:44:04 volumio69 systemd-logind[504]: New seat seat0. Jan 25 16:44:04 volumio69 avahi-daemon[505]: Loading service file /services/volumio.service. Jan 25 16:44:04 volumio69 avahi-daemon[505]: Network interface enumeration completed. Jan 25 16:44:04 volumio69 avahi-daemon[505]: Server startup complete. Host name is volumio69.local. Local service cookie is 1687530919. Jan 25 16:44:04 volumio69 avahi-daemon[505]: Service "Volumio69" (/services/volumio.service) successfully established. Jan 25 16:44:04 volumio69 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 25 16:44:04 volumio69 systemd[1]: Started Login Service. Jan 25 16:44:04 volumio69 systemd[1]: Reached target Sound Card. Jan 25 16:44:04 volumio69 systemd[1]: Reached target Remote File Systems (Pre). Jan 25 16:44:04 volumio69 systemd[1]: Reached target Remote File Systems. Jan 25 16:44:04 volumio69 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 25 16:44:04 volumio69 systemd[1]: Reached target RPC Port Mapper. Jan 25 16:44:04 volumio69 systemd[1]: Started Raise network interfaces. Jan 25 16:44:05 volumio69 loadcpufreq[566]: Loading cpufreq kernel modules...done (none). Jan 25 16:44:05 volumio69 systemd[1]: iptables.service: Succeeded. Jan 25 16:44:05 volumio69 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 25 16:44:05 volumio69 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 25 16:44:06 volumio69 cpufrequtils[604]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 25 16:44:06 volumio69 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 25 16:44:06 volumio69 haveged[445]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 25 16:44:06 volumio69 haveged[445]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 25 16:44:06 volumio69 haveged[445]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99433 Jan 25 16:44:06 volumio69 haveged[445]: haveged: fills: 0, generated: 0 Jan 25 16:44:06 volumio69 sh[390]: eth0: carrier acquired Jan 25 16:44:06 volumio69 dhcpcd[438]: eth0: carrier acquired Jan 25 16:44:06 volumio69 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jan 25 16:44:06 volumio69 sh[390]: DUID 00:01:00:01:2b:1f:e6:7c:b8:27:eb:f6:e8:68 Jan 25 16:44:06 volumio69 sh[390]: eth0: IAID eb:f6:e8:68 Jan 25 16:44:06 volumio69 dhcpcd[438]: DUID 00:01:00:01:2b:1f:e6:7c:b8:27:eb:f6:e8:68 Jan 25 16:44:06 volumio69 dhcpcd[438]: eth0: IAID eb:f6:e8:68 Jan 25 16:44:06 volumio69 sh[390]: eth0: adding address fe80::df8:1bdd:8419:d540 Jan 25 16:44:06 volumio69 sh[390]: ipv6_addaddr1: Permission denied Jan 25 16:44:06 volumio69 dhcpcd[438]: eth0: adding address fe80::df8:1bdd:8419:d540 Jan 25 16:44:06 volumio69 dhcpcd[438]: ipv6_addaddr1: Permission denied Jan 25 16:44:06 volumio69 systemd[1]: Started WPA supplicant. Jan 25 16:44:06 volumio69 systemd[1]: Reached target Network. Jan 25 16:44:06 volumio69 wpa_supplicant[494]: Successfully initialized wpa_supplicant Jan 25 16:44:06 volumio69 systemd[1]: Starting Music Player Daemon... Jan 25 16:44:06 volumio69 systemd[1]: Starting OpenBSD Secure Shell server... Jan 25 16:44:06 volumio69 systemd[1]: Starting Permit User Sessions... Jan 25 16:44:06 volumio69 systemd[1]: Reached target Network is Online. Jan 25 16:44:06 volumio69 systemd[1]: Starting Samba NMB Daemon... Jan 25 16:44:06 volumio69 systemd[1]: Starting LSB: Brings up/down network automatically... Jan 25 16:44:06 volumio69 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 25 16:44:06 volumio69 systemd[1]: Starting /etc/rc.local Compatibility... Jan 25 16:44:06 volumio69 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 25 16:44:06 volumio69 systemd[1]: Starting Network Time Service... Jan 25 16:44:06 volumio69 systemd[1]: Started Permit User Sessions. Jan 25 16:44:06 volumio69 systemd[1]: Started /etc/rc.local Compatibility. Jan 25 16:44:06 volumio69 systemd[1]: Starting Hold until boot process finishes up... Jan 25 16:44:06 volumio69 systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 25 16:44:06 volumio69 systemd[1]: Received SIGRTMIN+21 from PID 233 (plymouthd). Jan 25 16:44:06 volumio69 systemd[1]: Received SIGRTMIN+21 from PID 233 (plymouthd). Jan 25 16:44:06 volumio69 systemd[1]: plymouth-quit.service: Succeeded. Jan 25 16:44:06 volumio69 systemd[1]: Started Terminate Plymouth Boot Screen. Jan 25 16:44:06 volumio69 systemd[1]: plymouth-start.service: Succeeded. Jan 25 16:44:06 volumio69 systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 25 16:44:06 volumio69 systemd[1]: Started Hold until boot process finishes up. Jan 25 16:44:06 volumio69 systemd[1]: Started Getty on tty1. Jan 25 16:44:06 volumio69 systemd[1]: Reached target Login Prompts. Jan 25 16:44:06 volumio69 ifplugd(eth0)[646]: ifplugd 0.28 initializing. Jan 25 16:44:06 volumio69 ifplugd(eth0)[646]: Using interface eth0/B8:27:EB:F6:E8:68 with driver (version: 5.10.92-v7+) Jan 25 16:44:06 volumio69 ifplugd(eth0)[646]: Using detection mode: SIOCETHTOOL Jan 25 16:44:06 volumio69 ifplugd(eth0)[646]: Initialization complete, link beat detected. Jan 25 16:44:06 volumio69 sh[390]: eth0: soliciting an IPv6 router Jan 25 16:44:06 volumio69 dhcpcd[438]: eth0: soliciting an IPv6 router Jan 25 16:44:06 volumio69 ifplugd(eth0)[646]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 25 16:44:07 volumio69 ntpd[628]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 25 16:44:07 volumio69 ntpd[628]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 25 16:44:07 volumio69 systemd[1]: Started Network Time Service. Jan 25 16:44:07 volumio69 ifplugd(eth0)[646]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 25 16:44:07 volumio69 sh[390]: eth0: soliciting a DHCP lease Jan 25 16:44:07 volumio69 dhcpcd[438]: eth0: soliciting a DHCP lease Jan 25 16:44:07 volumio69 systemd[1]: systemd-rfkill.service: Succeeded. Jan 25 16:44:07 volumio69 ntpd[651]: proto: precision = 0.729 usec (-20) Jan 25 16:44:07 volumio69 ntpd[651]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 25 16:44:07 volumio69 ntpd[651]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 25 16:44:07 volumio69 ntpd[651]: Listen and drop on 0 v6wildcard [::]:123 Jan 25 16:44:07 volumio69 ntpd[651]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 25 16:44:07 volumio69 ntpd[651]: Listen normally on 2 lo 127.0.0.1:123 Jan 25 16:44:07 volumio69 ntpd[651]: Listening on routing socket on fd #19 for interface updates Jan 25 16:44:07 volumio69 ntpd[651]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 25 16:44:07 volumio69 ntpd[651]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 25 16:44:07 volumio69 volumio-remote-updater[488]: Error: No active session Jan 25 16:44:07 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:07] [info] asio async_connect error: system:111 (Connection refused) Jan 25 16:44:07 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 25 16:44:07 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:07] [error] handle_connect error: Underlying Transport Error Jan 25 16:44:08 volumio69 ntpd[651]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 25 16:44:08 volumio69 sshd[660]: Server listening on 0.0.0.0 port 22. Jan 25 16:44:08 volumio69 sshd[660]: Server listening on :: port 22. Jan 25 16:44:08 volumio69 systemd[1]: Started OpenBSD Secure Shell server. Jan 25 16:44:08 volumio69 volumio[498]: Could not open config: /tmp/upmpdcli.conf Jan 25 16:44:08 volumio69 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 25 16:44:08 volumio69 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 25 16:44:09 volumio69 ntpd[651]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 25 16:44:09 volumio69 kernel: Bluetooth: Core ver 2.22 Jan 25 16:44:09 volumio69 kernel: NET: Registered protocol family 31 Jan 25 16:44:09 volumio69 kernel: Bluetooth: HCI device and connection manager initialized Jan 25 16:44:09 volumio69 kernel: Bluetooth: HCI socket layer initialized Jan 25 16:44:09 volumio69 kernel: Bluetooth: L2CAP socket layer initialized Jan 25 16:44:09 volumio69 kernel: Bluetooth: SCO socket layer initialized Jan 25 16:44:09 volumio69 kernel: Bluetooth: HCI UART driver ver 2.3 Jan 25 16:44:09 volumio69 kernel: Bluetooth: HCI UART protocol H4 registered Jan 25 16:44:09 volumio69 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 25 16:44:09 volumio69 kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 25 16:44:09 volumio69 btuart[500]: bcm43xx_init Jan 25 16:44:09 volumio69 btuart[500]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Jan 25 16:44:09 volumio69 btuart[500]: Set BDADDR UART: b8:27:eb:5c:42:c2 Jan 25 16:44:09 volumio69 btuart[500]: Set Controller UART speed to 460800 bit/s Jan 25 16:44:09 volumio69 btuart[500]: Device setup complete Jan 25 16:44:09 volumio69 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 25 16:44:09 volumio69 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 25 16:44:09 volumio69 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 25 16:44:09 volumio69 systemd[1]: Created slice system-bthelper.slice. Jan 25 16:44:09 volumio69 systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 25 16:44:09 volumio69 nmbd[629]: [2023/01/25 16:44:09.670695, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 25 16:44:09 volumio69 nmbd[629]: started asyncdns process 677 Jan 25 16:44:09 volumio69 nmbd[629]: [2023/01/25 16:44:09.673290, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 25 16:44:09 volumio69 nmbd[629]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 25 16:44:09 volumio69 nmbd[629]: [2023/01/25 16:44:09.673548, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 25 16:44:09 volumio69 nmbd[629]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 25 16:44:09 volumio69 bthelper[675]: Raspberry Pi BDADDR already set Jan 25 16:44:09 volumio69 systemd[1]: Started Raspberry Pi bluetooth helper. Jan 25 16:44:09 volumio69 systemd[1]: Starting Bluetooth service... Jan 25 16:44:10 volumio69 bluetoothd[685]: Bluetooth daemon 5.50 Jan 25 16:44:10 volumio69 bluetoothd[685]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 25 16:44:10 volumio69 systemd[1]: Started Bluetooth service. Jan 25 16:44:10 volumio69 systemd[1]: Reached target Bluetooth. Jan 25 16:44:10 volumio69 bluetoothd[685]: Starting SDP server Jan 25 16:44:10 volumio69 bluetoothd[685]: Excluding (cli) sap Jan 25 16:44:10 volumio69 bluetoothd[685]: Bluetooth management interface 1.18 initialized Jan 25 16:44:10 volumio69 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 25 16:44:10 volumio69 kernel: Bluetooth: BNEP filters: protocol multicast Jan 25 16:44:10 volumio69 kernel: Bluetooth: BNEP socket layer initialized Jan 25 16:44:10 volumio69 dbus-daemon[483]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=685 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 25 16:44:10 volumio69 ntpd[651]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 25 16:44:10 volumio69 systemd[1]: Starting Hostname Service... Jan 25 16:44:10 volumio69 bluetoothd[685]: Failed to set privacy: Rejected (0x0b) Jan 25 16:44:10 volumio69 dbus-daemon[483]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 25 16:44:10 volumio69 systemd[1]: Started Hostname Service. Jan 25 16:44:11 volumio69 sh[390]: eth0: offered 192.168.1.152 from 192.168.1.254 Jan 25 16:44:11 volumio69 sh[390]: eth0: ignoring offer of 192.168.1.152 from 192.168.1.254 Jan 25 16:44:11 volumio69 dhcpcd[438]: eth0: offered 192.168.1.152 from 192.168.1.254 Jan 25 16:44:11 volumio69 dhcpcd[438]: eth0: ignoring offer of 192.168.1.152 from 192.168.1.254 Jan 25 16:44:11 volumio69 ntpd[651]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 25 16:44:11 volumio69 sh[390]: eth0: probing address 192.168.1.152/24 Jan 25 16:44:11 volumio69 dhcpcd[438]: eth0: probing address 192.168.1.152/24 Jan 25 16:44:11 volumio69 wireless.js[502]: Cleaning previous... Jan 25 16:44:11 volumio69 sudo[697]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 25 16:44:11 volumio69 sudo[697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:11 volumio69 sudo[697]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:11 volumio69 sudo[699]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 25 16:44:11 volumio69 sudo[699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:12 volumio69 sudo[699]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:12 volumio69 mpd[647]: Jan 25 16:44 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 16:44:12 volumio69 wireless.js[502]: Stopped aP Jan 25 16:44:12 volumio69 sudo[706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 25 16:44:12 volumio69 sudo[706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:12 volumio69 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 25 16:44:12 volumio69 sudo[706]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:12 volumio69 sudo[708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 25 16:44:12 volumio69 sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:12 volumio69 sudo[708]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:12 volumio69 sudo[715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 25 16:44:12 volumio69 sudo[715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:12 volumio69 sudo[715]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:12 volumio69 sudo[717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 25 16:44:12 volumio69 sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:12 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:12] [info] asio async_connect error: system:111 (Connection refused) Jan 25 16:44:12 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 25 16:44:12 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:12] [error] handle_connect error: Underlying Transport Error Jan 25 16:44:12 volumio69 sudo[717]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:12 volumio69 wireless.js[502]: Start wireless flow Jan 25 16:44:12 volumio69 wireless.js[502]: Stopped hotspot (if there).. Jan 25 16:44:12 volumio69 sudo[722]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 25 16:44:12 volumio69 sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:12 volumio69 sudo[722]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:12 volumio69 sudo[724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 25 16:44:12 volumio69 sudo[724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:13 volumio69 sudo[724]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:13 volumio69 wireless.js[502]: DHCP IP Jan 25 16:44:13 volumio69 wireless.js[502]: Start ap Jan 25 16:44:13 volumio69 wpa_supplicant[726]: Successfully initialized wpa_supplicant Jan 25 16:44:13 volumio69 sudo[727]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 25 16:44:13 volumio69 sudo[727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:13 volumio69 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 25 16:44:13 volumio69 dhcpcd[728]: dev: loaded udev Jan 25 16:44:13 volumio69 dhcpcd-run-hooks[737]: wlan0: starting wpa_supplicant Jan 25 16:44:13 volumio69 dhcpcd-run-hooks[742]: wlan0: failed to start wpa_supplicant Jan 25 16:44:13 volumio69 dhcpcd-run-hooks[743]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jan 25 16:44:13 volumio69 dhcpcd[728]: wlan0: connected to Access Point `' Jan 25 16:44:13 volumio69 dhcpcd[728]: DUID 00:01:00:01:2b:1f:e6:7c:b8:27:eb:f6:e8:68 Jan 25 16:44:13 volumio69 dhcpcd[728]: eth0: IAID eb:f6:e8:68 Jan 25 16:44:13 volumio69 dhcpcd[728]: eth0: adding address fe80::df8:1bdd:8419:d540 Jan 25 16:44:13 volumio69 dhcpcd[728]: ipv6_addaddr1: Permission denied Jan 25 16:44:13 volumio69 dhcpcd[728]: wlan0: waiting for carrier Jan 25 16:44:14 volumio69 systemd[1]: systemd-rfkill.service: Succeeded. Jan 25 16:44:14 volumio69 wireless.js[502]: trying... Jan 25 16:44:14 volumio69 sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:14 volumio69 sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:14 volumio69 sudo[754]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:14 volumio69 dhcpcd[728]: eth0: soliciting a DHCP lease Jan 25 16:44:14 volumio69 dhcpcd[728]: eth0: offered 192.168.1.152 from 192.168.1.254 Jan 25 16:44:14 volumio69 dhcpcd[728]: eth0: probing address 192.168.1.152/24 Jan 25 16:44:14 volumio69 mpd[647]: Jan 25 16:44 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 25 16:44:14 volumio69 mpd[647]: Jan 25 16:44 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 25 16:44:14 volumio69 bthelper[675]: Changing power off succeeded Jan 25 16:44:14 volumio69 systemd[1]: Started Music Player Daemon. Jan 25 16:44:14 volumio69 dhcpcd[728]: eth0: soliciting an IPv6 router Jan 25 16:44:14 volumio69 bthelper[675]: [CHG] Controller B8:27:EB:5C:42:C2 Class: 0x0000041c Jan 25 16:44:14 volumio69 bthelper[675]: Changing power on succeeded Jan 25 16:44:15 volumio69 wireless.js[502]: trying... Jan 25 16:44:15 volumio69 sudo[766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:15 volumio69 sudo[766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:15 volumio69 sudo[766]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:16 volumio69 sh[390]: eth0: leased 192.168.1.152 for 86400 seconds Jan 25 16:44:16 volumio69 sh[390]: eth0: adding route to 192.168.1.0/24 Jan 25 16:44:16 volumio69 sh[390]: eth0: adding default route via 192.168.1.254 Jan 25 16:44:16 volumio69 dhcpcd[438]: eth0: leased 192.168.1.152 for 86400 seconds Jan 25 16:44:16 volumio69 dhcpcd[438]: eth0: adding route to 192.168.1.0/24 Jan 25 16:44:16 volumio69 avahi-daemon[505]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.152. Jan 25 16:44:16 volumio69 avahi-daemon[505]: New relevant interface eth0.IPv4 for mDNS. Jan 25 16:44:16 volumio69 dhcpcd[438]: eth0: adding default route via 192.168.1.254 Jan 25 16:44:16 volumio69 avahi-daemon[505]: Registering new address record for 192.168.1.152 on eth0.IPv4. Jan 25 16:44:16 volumio69 wireless.js[502]: trying... Jan 25 16:44:16 volumio69 sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:16 volumio69 sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:16 volumio69 sudo[778]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:16 volumio69 sh[390]: forked to background, child pid 792 Jan 25 16:44:16 volumio69 dhcpcd[438]: forked to background, child pid 792 Jan 25 16:44:16 volumio69 ifplugd(eth0)[646]: client: ifup: interface eth0 already configured Jan 25 16:44:16 volumio69 sh[390]: eth0=eth0 Jan 25 16:44:16 volumio69 ifplugd(eth0)[646]: Program executed successfully. Jan 25 16:44:16 volumio69 ifplugd[622]: Network Interface Plugging Daemon...start eth0...done. Jan 25 16:44:16 volumio69 systemd[1]: Started LSB: Brings up/down network automatically. Jan 25 16:44:17 volumio69 wireless.js[502]: trying... Jan 25 16:44:17 volumio69 sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:17 volumio69 sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:17 volumio69 sudo[819]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:17 volumio69 kernel: cam1-reg: disabling Jan 25 16:44:17 volumio69 kernel: cam-dummy-reg: disabling Jan 25 16:44:18 volumio69 ntpd[651]: Listen normally on 3 eth0 192.168.1.152:123 Jan 25 16:44:18 volumio69 ntpd[651]: new interface(s) found: waking up resolver Jan 25 16:44:18 volumio69 wireless.js[502]: trying... Jan 25 16:44:18 volumio69 sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:18 volumio69 sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:18 volumio69 sudo[822]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:19 volumio69 wireless.js[502]: trying... Jan 25 16:44:19 volumio69 sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:19 volumio69 sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:19 volumio69 sudo[825]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:19 volumio69 systemd[1]: Started Samba NMB Daemon. Jan 25 16:44:19 volumio69 nmbd[629]: [2023/01/25 16:44:19.685044, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 25 16:44:19 volumio69 nmbd[629]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 25 16:44:19 volumio69 systemd[1]: Starting Samba Winbind Daemon... Jan 25 16:44:20 volumio69 winbindd[827]: [2023/01/25 16:44:20.040161, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 25 16:44:20 volumio69 winbindd[827]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 25 16:44:20 volumio69 systemd[1]: Started Samba Winbind Daemon. Jan 25 16:44:20 volumio69 winbindd[827]: [2023/01/25 16:44:20.073812, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 25 16:44:20 volumio69 systemd[1]: Starting Samba SMB Daemon... Jan 25 16:44:20 volumio69 winbindd[827]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 25 16:44:20 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:20] [info] asio async_connect error: system:111 (Connection refused) Jan 25 16:44:20 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 25 16:44:20 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:20] [error] handle_connect error: Underlying Transport Error Jan 25 16:44:20 volumio69 dhcpcd[728]: eth0: leased 192.168.1.152 for 86400 seconds Jan 25 16:44:20 volumio69 dhcpcd[728]: eth0: adding route to 192.168.1.0/24 Jan 25 16:44:20 volumio69 dhcpcd[728]: eth0: adding default route via 192.168.1.254 Jan 25 16:44:20 volumio69 wireless.js[502]: trying... Jan 25 16:44:20 volumio69 sudo[840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:20 volumio69 sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:20 volumio69 sudo[840]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:20 volumio69 dhcpcd[728]: forked to background, child pid 860 Jan 25 16:44:20 volumio69 sudo[727]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:20 volumio69 winbindd[827]: [2023/01/25 16:44:20.679818, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 25 16:44:20 volumio69 winbindd[827]: res_names->count = 3, expected 4 Jan 25 16:44:20 volumio69 smbd[832]: [2023/01/25 16:44:20.715301, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 25 16:44:20 volumio69 smbd[832]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 25 16:44:20 volumio69 systemd[1]: Started Samba SMB Daemon. Jan 25 16:44:21 volumio69 wireless.js[502]: trying... Jan 25 16:44:21 volumio69 sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:21 volumio69 sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:21 volumio69 sudo[866]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:22 volumio69 wireless.js[502]: trying... Jan 25 16:44:22 volumio69 sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:22 volumio69 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:22 volumio69 sudo[869]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:23 volumio69 wireless.js[502]: trying... Jan 25 16:44:23 volumio69 sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:23 volumio69 sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:23 volumio69 sudo[872]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:24 volumio69 wireless.js[502]: trying... Jan 25 16:44:24 volumio69 sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:24 volumio69 sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:24 volumio69 sudo[875]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:25 volumio69 wireless.js[502]: trying... Jan 25 16:44:25 volumio69 wpa_supplicant[741]: wlan0: Failed to initiate sched scan Jan 25 16:44:25 volumio69 sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:25 volumio69 sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:25 volumio69 sudo[878]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:26 volumio69 wireless.js[502]: trying... Jan 25 16:44:26 volumio69 sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:26 volumio69 sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:26 volumio69 sudo[881]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:27 volumio69 wireless.js[502]: trying... Jan 25 16:44:27 volumio69 sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:27 volumio69 sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:27 volumio69 sudo[884]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:28 volumio69 wireless.js[502]: trying... Jan 25 16:44:28 volumio69 sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:28 volumio69 sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:28 volumio69 sudo[887]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:29 volumio69 wireless.js[502]: trying... Jan 25 16:44:29 volumio69 sudo[890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:29 volumio69 sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:29 volumio69 sudo[890]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:30 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:30] [info] asio async_connect error: system:111 (Connection refused) Jan 25 16:44:30 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 25 16:44:30 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:30] [error] handle_connect error: Underlying Transport Error Jan 25 16:44:30 volumio69 wireless.js[502]: trying... Jan 25 16:44:30 volumio69 sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:30 volumio69 sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:30 volumio69 sudo[893]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:31 volumio69 wpa_supplicant[741]: wlan0: Failed to initiate sched scan Jan 25 16:44:31 volumio69 wireless.js[502]: trying... Jan 25 16:44:31 volumio69 sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:31 volumio69 sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:31 volumio69 sudo[896]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:32 volumio69 systemd[1]: systemd-fsckd.service: Succeeded. Jan 25 16:44:32 volumio69 wireless.js[502]: trying... Jan 25 16:44:32 volumio69 sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:32 volumio69 sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:32 volumio69 sudo[899]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:33 volumio69 wireless.js[502]: trying... Jan 25 16:44:33 volumio69 sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 25 16:44:33 volumio69 sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:33 volumio69 sudo[902]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:34 volumio69 wireless.js[502]: Overtime, starting plan B Jan 25 16:44:34 volumio69 systemd[1]: Started Wireless Services. Jan 25 16:44:34 volumio69 systemd[1]: Started Volumio Backend Module. Jan 25 16:44:34 volumio69 systemd[1]: Started Volumio Streaming Daemon. Jan 25 16:44:34 volumio69 systemd[1]: Reached target Multi-User System. Jan 25 16:44:34 volumio69 systemd[1]: Reached target Graphical Interface. Jan 25 16:44:34 volumio69 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 25 16:44:34 volumio69 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 25 16:44:34 volumio69 systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 25 16:44:34 volumio69 systemd[1]: Startup finished in 11.698s (kernel) + 39.641s (userspace) = 51.340s. Jan 25 16:44:34 volumio69 volumio-streaming-daemon[907]: ############################ Jan 25 16:44:34 volumio69 volumio-streaming-daemon[907]: # Volumio Streaming Daemon # Jan 25 16:44:34 volumio69 volumio-streaming-daemon[907]: # Running on port 7777 # Jan 25 16:44:34 volumio69 volumio-streaming-daemon[907]: ############################ Jan 25 16:44:34 volumio69 volumio-streaming-daemon[907]: Environment: production Jan 25 16:44:36 volumio69 wpa_supplicant[741]: wlan0: Failed to initiate sched scan Jan 25 16:44:37 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:37 volumio69 volumio[906]: info: ----- Volumio3 ---- Jan 25 16:44:37 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:37 volumio69 volumio[906]: info: ----- System startup ---- Jan 25 16:44:37 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:38 volumio69 volumio[906]: info: MYVOLUMIO Environment detected Jan 25 16:44:39 volumio69 volumio[906]: info: Plugin folders cleanup Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning into folder /volumio/app/plugins/ Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category audio_interface Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category miscellanea Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category music_service Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category plugins.json Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category system_controller Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category user_interface Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning into folder /data/plugins/ Jan 25 16:44:39 volumio69 volumio[906]: info: Scanning category audio_interface Jan 25 16:44:39 volumio69 volumio[906]: info: Plugin folders cleanup completed Jan 25 16:44:39 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:39 volumio69 volumio[906]: info: ----- Core plugins startup ---- Jan 25 16:44:39 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:39 volumio69 volumio[906]: info: Loading plugins from folder /volumio/app/plugins/ Jan 25 16:44:39 volumio69 volumio[906]: info: Adding plugin upnp to MyMusic Plugins Jan 25 16:44:39 volumio69 volumio[906]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 25 16:44:39 volumio69 volumio[906]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 25 16:44:39 volumio69 volumio[906]: info: Loading plugins from folder /data/plugins/ Jan 25 16:44:39 volumio69 volumio[906]: info: Loading plugin "system"... Jan 25 16:44:39 volumio69 volumio[906]: info: Loading plugin "appearance"... Jan 25 16:44:40 volumio69 volumio-remote-updater[488]: [2023-01-25 16:44:40] [connect] Successful connection Jan 25 16:44:40 volumio69 systemd[1]: systemd-hostnamed.service: Succeeded. Jan 25 16:44:40 volumio69 volumio[906]: info: Loading plugin "network"... Jan 25 16:44:40 volumio69 volumio[906]: info: Refreshing Cached IP Addresses Jan 25 16:44:40 volumio69 sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 16:44:40 volumio69 sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:40 volumio69 sudo[933]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:40 volumio69 volumio[906]: info: Loading plugin "services"... Jan 25 16:44:40 volumio69 sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 16:44:40 volumio69 sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:40 volumio69 volumio[906]: info: Loading plugin "alsa_controller"... Jan 25 16:44:40 volumio69 sudo[935]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:40 volumio69 sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 25 16:44:40 volumio69 sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:41 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 16:44:41 volumio69 volumio[906]: info: Loading plugin "wizard"... Jan 25 16:44:41 volumio69 volumio[906]: info: Loading plugin "volumio_command_line_client"... Jan 25 16:44:41 volumio69 volumio[906]: info: Loading plugin "upnp"... Jan 25 16:44:41 volumio69 volumio[906]: info: [1674665081108] Starting Upmpd Daemon Jan 25 16:44:41 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 16:44:41 volumio69 volumio[906]: info: Loading plugin "my_music"... Jan 25 16:44:41 volumio69 volumio[906]: info: Loading plugin "mpd"... Jan 25 16:44:41 volumio69 sudo[938]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:41 volumio69 volumio[906]: info: Creating MPD Configuration file Jan 25 16:44:41 volumio69 volumio[906]: info: Loading plugin "upnp_browser"... Jan 25 16:44:41 volumio69 sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 16:44:41 volumio69 sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:41 volumio69 sudo[946]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:41 volumio69 sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 16:44:41 volumio69 sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:41 volumio69 systemd[1]: Stopping Music Player Daemon... Jan 25 16:44:42 volumio69 systemd[1]: mpd.service: Succeeded. Jan 25 16:44:42 volumio69 systemd[1]: Stopped Music Player Daemon. Jan 25 16:44:42 volumio69 systemd[1]: Starting Music Player Daemon... Jan 25 16:44:42 volumio69 volumio[906]: info: Loading plugin "networkfs"... Jan 25 16:44:42 volumio69 wpa_supplicant[741]: wlan0: Failed to initiate sched scan Jan 25 16:44:42 volumio69 volumio[906]: info: Starting Udev Watcher for removable devices Jan 25 16:44:42 volumio69 sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.74/Core /mnt/NAS/Core Jan 25 16:44:42 volumio69 sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:42 volumio69 sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=rasp3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.99/Toshiba4G /mnt/NAS/Toshiba4G Jan 25 16:44:42 volumio69 sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:42 volumio69 kernel: FS-Cache: Netfs 'cifs' registered for caching Jan 25 16:44:42 volumio69 kernel: Key type cifs.spnego registered Jan 25 16:44:42 volumio69 kernel: Key type cifs.idmap registered Jan 25 16:44:42 volumio69 kernel: CIFS: Attempting to mount //192.168.1.99/Toshiba4G Jan 25 16:44:42 volumio69 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 25 16:44:42 volumio69 kernel: CIFS: Attempting to mount //192.168.1.74/Core Jan 25 16:44:43 volumio69 sudo[956]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:43 volumio69 volumio[906]: info: Ignoring mount for partition: boot Jan 25 16:44:43 volumio69 volumio[906]: info: Ignoring mount for partition: volumio Jan 25 16:44:43 volumio69 volumio[906]: info: Ignoring mount for partition: volumio_data Jan 25 16:44:43 volumio69 volumio[906]: error: Cannot associate FS Label, not mounting Jan 25 16:44:43 volumio69 volumio[906]: info: Mounting Device Toshiba4G Jan 25 16:44:43 volumio69 sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/Toshiba4G -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 25 16:44:43 volumio69 sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:43 volumio69 kernel: fuse: init (API version 7.32) Jan 25 16:44:43 volumio69 systemd[1]: Mounting FUSE Control File System... Jan 25 16:44:43 volumio69 systemd[1]: Mounted FUSE Control File System. Jan 25 16:44:43 volumio69 mpd[953]: Jan 25 16:44 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 16:44:46 volumio69 mpd[953]: Jan 25 16:44 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 25 16:44:46 volumio69 mpd[953]: Jan 25 16:44 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 25 16:44:46 volumio69 systemd[1]: Started Music Player Daemon. Jan 25 16:44:46 volumio69 sudo[948]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:47 volumio69 wpa_supplicant[741]: wlan0: Failed to initiate sched scan Jan 25 16:44:48 volumio69 ntfs-3g[1002]: Version 2017.3.23AR.3 integrated FUSE 28 Jan 25 16:44:48 volumio69 ntfs-3g[1002]: Mounted /dev/sda2 (Read-Write, label "Toshiba4G", NTFS 3.1) Jan 25 16:44:48 volumio69 ntfs-3g[1002]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 25 16:44:48 volumio69 ntfs-3g[1002]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Jan 25 16:44:48 volumio69 ntfs-3g[1002]: Global ownership and permissions enforced, configuration type 7 Jan 25 16:44:48 volumio69 sudo[994]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:48 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "alarm-clock"... Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "airplay_emulation"... Jan 25 16:44:48 volumio69 volumio[906]: info: Starting Shairport Sync Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "last_100"... Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "webradio"... Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "i2s_dacs"... Jan 25 16:44:48 volumio69 volumio[906]: info: I2S DAC not set, start Auto-detection Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "volumiodiscovery"... Jan 25 16:44:48 volumio69 volumio[906]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 25 16:44:48 volumio69 volumio[906]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 16:44:48 volumio69 volumio[906]: *** WARNING *** For more information see Jan 25 16:44:48 volumio69 volumio[906]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 25 16:44:48 volumio69 node[906]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 25 16:44:48 volumio69 volumio[906]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 16:44:48 volumio69 volumio[906]: *** WARNING *** For more information see Jan 25 16:44:48 volumio69 node[906]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 16:44:48 volumio69 node[906]: *** WARNING *** For more information see Jan 25 16:44:48 volumio69 node[906]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 25 16:44:48 volumio69 node[906]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 16:44:48 volumio69 node[906]: *** WARNING *** For more information see Jan 25 16:44:48 volumio69 volumio[906]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 25 16:44:48 volumio69 volumio[906]: info: Discovery: Started advertising with name: Volumio69 Jan 25 16:44:48 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "outputs"... Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "albumart"... Jan 25 16:44:48 volumio69 volumio[906]: info: Plugin example_plugin is not enabled Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "inputs"... Jan 25 16:44:48 volumio69 volumio[906]: info: Loading plugin "updater_comm"... Jan 25 16:44:49 volumio69 volumio[906]: info: Plugin mpdemulation is not enabled Jan 25 16:44:49 volumio69 volumio[906]: info: Loading plugin "rest_api"... Jan 25 16:44:49 volumio69 volumio[906]: info: Loading plugin "websocket"... Jan 25 16:44:49 volumio69 volumio[906]: info: Loading plugin "fusiondsp"... Jan 25 16:44:49 volumio69 sudo[958]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:49 volumio69 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 25 16:44:49 volumio69 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 25 16:44:49 volumio69 volumio[906]: Forking 3 albumart workers Jan 25 16:44:49 volumio69 volumio[906]: info: Applying required configuration parameters for plugin fusiondsp Jan 25 16:44:50 volumio69 volumio[906]: info: Loading i18n strings for locale en Jan 25 16:44:50 volumio69 volumio[906]: Updating browse sources language Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 16:44:50 volumio69 volumio[906]: Starting albumart workers Jan 25 16:44:50 volumio69 volumio[906]: Starting albumart workers Jan 25 16:44:50 volumio69 volumio[906]: Starting albumart workers Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::initPlayerControls Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: Express server listening on port 3000 Jan 25 16:44:50 volumio69 volumio[906]: [Metrics] WebUI: 14s 37.33ms Jan 25 16:44:50 volumio69 volumio[906]: info: CoreStateMachine::resetVolumioState Jan 25 16:44:50 volumio69 volumio[906]: info: CoreStateMachine::getcurrentVolume Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 16:44:50 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:50 volumio69 volumio[906]: info: Cannot mount NAS Toshiba4G at system boot, trial number 1 ,retrying in 5 seconds Jan 25 16:44:50 volumio69 volumio[906]: info: MPD Permissions set Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 16:44:51 volumio69 volumio[906]: info: Discovery: Getting this device information Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioGetState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 16:44:51 volumio69 volumio[906]: info: Discovery: Getting this device information Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioGetState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 16:44:51 volumio69 volumio[906]: info: Discovery: Getting this device information Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioGetState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 16:44:51 volumio69 volumio[906]: info: MPD running with PID953 Jan 25 16:44:51 volumio69 volumio[906]: ,establishing connection Jan 25 16:44:51 volumio69 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 16:44:51 volumio69 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:51 volumio69 sudo[1050]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 16:44:51 volumio69 sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:51 volumio69 sudo[1054]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: Reloading queue from file Jan 25 16:44:51 volumio69 volumio[906]: error: updateQueue error: null Jan 25 16:44:51 volumio69 volumio[906]: info: VolumeController:: Volume=43 Mute =false Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::pushState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioPushState Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::updateTrackBlock Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrackBlock Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::setRepeat true single undefined Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::pushState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioPushState Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::setRandom true Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::pushState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioPushState Jan 25 16:44:51 volumio69 volumio[906]: info: Setting Device type: Raspberry PI Jan 25 16:44:51 volumio69 volumio[906]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.64 UA: unknown Total Clients: 1 Jan 25 16:44:51 volumio69 volumio[906]: info: Completed loading Core Plugins Jan 25 16:44:51 volumio69 volumio[906]: info: Preparing to generate the ALSA configuration file Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioGetState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: Discovery: adding 0af5a332-ac71-477c-b2ce-f7fdab1a9f29 Jan 25 16:44:51 volumio69 volumio[906]: info: Discovery: Found device Volumio69 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioGetState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 25 16:44:51 volumio69 volumio[906]: info: Reading ALSA contributions from plugins. Jan 25 16:44:51 volumio69 volumio[906]: info: VolumeController:: Volume=43 Mute =false Jan 25 16:44:51 volumio69 volumio[906]: info: CoreStateMachine::pushState Jan 25 16:44:51 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioPushState Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 25 16:44:51 volumio69 volumio[906]: info: Asound.conf file unchanged, so no further update is needed Jan 25 16:44:51 volumio69 sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 25 16:44:51 volumio69 sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:51 volumio69 volumio[906]: info: Output device has changed, restarting MPD Jan 25 16:44:51 volumio69 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 25 16:44:51 volumio69 volumio[906]: info: Output device has changed, restarting Shairport Sync Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:51 volumio69 sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 16:44:51 volumio69 sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:51 volumio69 sudo[1083]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:51 volumio69 sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 16:44:51 volumio69 sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:51 volumio69 sudo[1086]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:51 volumio69 systemd[1]: Stopping Music Player Daemon... Jan 25 16:44:51 volumio69 volumio[906]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 16:44:51 volumio69 volumio[906]: info: ___________ START PLUGINS ___________ Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 16:44:51 volumio69 volumio[906]: info: [1674665091843] CoreMusicLibrary::Adding element Media Servers Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 16:44:51 volumio69 volumio[906]: Cannot find translation for sourceMedia Servers Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:51 volumio69 volumio[906]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 16:44:51 volumio69 volumio[906]: info: [1674665091982] CoreMusicLibrary::Adding element Last_100 Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 16:44:51 volumio69 volumio[906]: Cannot find translation for sourceMedia Servers Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 16:44:51 volumio69 volumio[906]: info: [1674665091989] CoreMusicLibrary::Adding element Webradio Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 16:44:51 volumio69 volumio[906]: Cannot find translation for sourceMedia Servers Jan 25 16:44:51 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 16:44:52 volumio69 volumio[906]: info: Loading i18n strings for locale en Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: mixtype--------------------- Hardware Jan 25 16:44:52 volumio69 volumio[906]: info: Volumio Calling Home Jan 25 16:44:52 volumio69 volumio[906]: info: Preparing to generate the ALSA configuration file Jan 25 16:44:52 volumio69 volumio[906]: info: Completed starting Core Plugins Jan 25 16:44:52 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:52 volumio69 volumio[906]: info: ----- MyVolumio plugins startup ---- Jan 25 16:44:52 volumio69 volumio[906]: info: ------------------------------------------- Jan 25 16:44:52 volumio69 volumio[906]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 25 16:44:52 volumio69 volumio[906]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 25 16:44:52 volumio69 volumio[906]: info: Reading ALSA contributions from plugins. Jan 25 16:44:52 volumio69 volumio[906]: info: MPD Permissions set Jan 25 16:44:52 volumio69 volumio[906]: info: Upmpdcli Daemon Started Jan 25 16:44:52 volumio69 volumio[906]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jan 25 16:44:52 volumio69 volumio[906]: info: Volumio called home Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: Starting Shairport Sync Jan 25 16:44:52 volumio69 volumio[906]: info: Starting Shairport Sync Jan 25 16:44:52 volumio69 volumio[906]: info: Starting Shairport Sync Jan 25 16:44:52 volumio69 sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 16:44:52 volumio69 sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:52 volumio69 sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 16:44:52 volumio69 sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:52 volumio69 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 25 16:44:52 volumio69 systemd[1]: shairport-sync.service: Succeeded. Jan 25 16:44:52 volumio69 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 25 16:44:52 volumio69 sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 16:44:52 volumio69 sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:52 volumio69 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 25 16:44:52 volumio69 sudo[1109]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:52 volumio69 volumio[906]: info: Asound.conf file unchanged, so no further update is needed Jan 25 16:44:52 volumio69 volumio[906]: info: Output device has changed, restarting MPD Jan 25 16:44:52 volumio69 volumio[906]: info: Output device has changed, restarting Shairport Sync Jan 25 16:44:52 volumio69 sudo[1111]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:52 volumio69 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 25 16:44:52 volumio69 sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 16:44:52 volumio69 sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:52 volumio69 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 25 16:44:52 volumio69 systemd[1]: shairport-sync.service: Succeeded. Jan 25 16:44:52 volumio69 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 25 16:44:52 volumio69 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 25 16:44:52 volumio69 sudo[1115]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:52 volumio69 sudo[1119]: pam_unix(sudo:session): session closed for user root Jan 25 16:44:52 volumio69 sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 16:44:52 volumio69 sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:52 volumio69 volumio[906]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 16:44:52 volumio69 volumio[906]: info: Shairport-Sync Started Jan 25 16:44:52 volumio69 volumio[906]: Error adding Membership: Error: addMembership EINVAL Jan 25 16:44:52 volumio69 volumio[906]: info: MPD Permissions set Jan 25 16:44:52 volumio69 volumio[906]: info: Shairport-Sync Started Jan 25 16:44:52 volumio69 volumio[906]: info: Shairport-Sync Started Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::volumioGetState Jan 25 16:44:52 volumio69 volumio[906]: info: CorePlayQueue::getTrack 0 Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:44:52 volumio69 volumio[906]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 16:44:52 volumio69 volumio[906]: Error: certificate is not yet valid Jan 25 16:44:52 volumio69 volumio[906]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 25 16:44:52 volumio69 volumio[906]: at TLSSocket.emit (events.js:315:20) Jan 25 16:44:52 volumio69 volumio[906]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 25 16:44:52 volumio69 volumio[906]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 25 16:44:52 volumio69 volumio[906]: code: 'CERT_NOT_YET_VALID' Jan 25 16:44:52 volumio69 volumio[906]: } Jan 25 16:44:52 volumio69 volumio[906]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 16:44:53 volumio69 sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-01-25 16:43 Jan 25 16:44:53 volumio69 sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 16:44:53 volumio69 wpa_supplicant[741]: wlan0: Failed to initiate sched scan PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"