-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2019-02-14 10:12:40 UTC. -- Feb 14 10:11:59 volumio-box kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 volumio-box 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 Feb 14 10:11:59 volumio-box kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 10:11:59 volumio-box kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 volumio-box kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:11:59 volumio-box kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 14 10:11:59 volumio-box kernel: random: fast init done Feb 14 10:11:59 volumio-box kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 volumio-box kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB Feb 14 10:11:59 volumio-box kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 volumio-box kernel: Zone ranges: Feb 14 10:11:59 volumio-box kernel: DMA [mem 0x0000000000000000-0x000000003dffffff] Feb 14 10:11:59 volumio-box kernel: Normal empty Feb 14 10:11:59 volumio-box kernel: Movable zone start for each node Feb 14 10:11:59 volumio-box kernel: Early memory node ranges Feb 14 10:11:59 volumio-box kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Feb 14 10:11:59 volumio-box kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff] Feb 14 10:11:59 volumio-box kernel: On node 0 totalpages: 253952 Feb 14 10:11:59 volumio-box kernel: DMA zone: 2232 pages used for memmap Feb 14 10:11:59 volumio-box kernel: DMA zone: 0 pages reserved Feb 14 10:11:59 volumio-box kernel: DMA zone: 253952 pages, LIFO batch:63 Feb 14 10:11:59 volumio-box kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Feb 14 10:11:59 volumio-box kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Feb 14 10:11:59 volumio-box kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 volumio-box kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Feb 14 10:11:59 volumio-box 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=640 bcm2708_fb.fbheight=480 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 Feb 14 10:11:59 volumio-box kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Feb 14 10:11:59 volumio-box kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 volumio-box kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 volumio-box kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 volumio-box kernel: Memory: 910432K/1015808K available (10240K kernel code, 1312K rwdata, 2952K rodata, 1024K init, 862K bss, 39840K reserved, 65536K cma-reserved) Feb 14 10:11:59 volumio-box kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 volumio-box kernel: ftrace: allocating 32087 entries in 95 pages Feb 14 10:11:59 volumio-box kernel: ftrace: allocated 95 pages with 6 groups Feb 14 10:11:59 volumio-box kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 volumio-box kernel: Rude variant of Tasks RCU enabled. Feb 14 10:11:59 volumio-box kernel: Tracing variant of Tasks RCU enabled. Feb 14 10:11:59 volumio-box kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 volumio-box kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 volumio-box kernel: random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1 Feb 14 10:11:59 volumio-box kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:11:59 volumio-box kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:11:59 volumio-box kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:11:59 volumio-box kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:11:59 volumio-box kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 volumio-box kernel: printk: console [tty1] enabled Feb 14 10:11:59 volumio-box kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:11:59 volumio-box kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 volumio-box kernel: LSM: Security Framework initializing Feb 14 10:11:59 volumio-box kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumio-box kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumio-box kernel: cgroup: Disabling memory control group subsystem Feb 14 10:11:59 volumio-box kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 volumio-box kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 volumio-box kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:11:59 volumio-box kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 volumio-box kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 volumio-box kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 volumio-box kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 volumio-box kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 volumio-box kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 volumio-box kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:11:59 volumio-box kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 volumio-box kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 volumio-box kernel: devtmpfs: initialized Feb 14 10:11:59 volumio-box kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 10:11:59 volumio-box kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 volumio-box kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumio-box kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 volumio-box kernel: NET: Registered protocol family 16 Feb 14 10:11:59 volumio-box kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 volumio-box kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 volumio-box kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 volumio-box kernel: audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 volumio-box kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 volumio-box kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 volumio-box kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 volumio-box kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:11:59 volumio-box kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:58:22, variant start Feb 14 10:11:59 volumio-box kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Feb 14 10:11:59 volumio-box kernel: Kprobes globally optimized Feb 14 10:11:59 volumio-box kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 volumio-box kernel: SCSI subsystem initialized Feb 14 10:11:59 volumio-box kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 volumio-box kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 volumio-box kernel: usbcore: registered new device driver usb Feb 14 10:11:59 volumio-box kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 volumio-box kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 volumio-box kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 volumio-box kernel: FS-Cache: Loaded Feb 14 10:11:59 volumio-box kernel: CacheFiles: Loaded Feb 14 10:11:59 volumio-box kernel: NET: Registered protocol family 2 Feb 14 10:11:59 volumio-box kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 10:11:59 volumio-box kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:11:59 volumio-box kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 volumio-box kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumio-box kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 volumio-box kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumio-box kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumio-box kernel: NET: Registered protocol family 1 Feb 14 10:11:59 volumio-box kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 volumio-box kernel: RPC: Registered udp transport module. Feb 14 10:11:59 volumio-box kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 volumio-box kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 volumio-box kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 volumio-box kernel: Freeing initrd memory: 13028K Feb 14 10:11:59 volumio-box kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 10:11:59 volumio-box kernel: Initialise system trusted keyrings Feb 14 10:11:59 volumio-box kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:11:59 volumio-box kernel: zbud: loaded Feb 14 10:11:59 volumio-box kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:11:59 volumio-box kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 volumio-box kernel: Key type id_resolver registered Feb 14 10:11:59 volumio-box kernel: Key type id_legacy registered Feb 14 10:11:59 volumio-box kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 volumio-box kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 10:11:59 volumio-box kernel: Key type asymmetric registered Feb 14 10:11:59 volumio-box kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 volumio-box kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:11:59 volumio-box kernel: io scheduler mq-deadline registered Feb 14 10:11:59 volumio-box kernel: io scheduler kyber registered Feb 14 10:11:59 volumio-box kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 volumio-box kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:11:59 volumio-box kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:11:59 volumio-box kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:11:59 volumio-box kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 volumio-box kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Feb 14 10:11:59 volumio-box kernel: brd: module loaded Feb 14 10:11:59 volumio-box kernel: loop: module loaded Feb 14 10:11:59 volumio-box kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 volumio-box kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:11:59 volumio-box kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 volumio-box kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 volumio-box kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:11:59 volumio-box kernel: Core Release: 2.80a Feb 14 10:11:59 volumio-box kernel: Setting default values for core params Feb 14 10:11:59 volumio-box kernel: Finished setting default values for core params Feb 14 10:11:59 volumio-box kernel: Using Buffer DMA mode Feb 14 10:11:59 volumio-box kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:11:59 volumio-box kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:11:59 volumio-box kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:11:59 volumio-box kernel: Dedicated Tx FIFOs mode Feb 14 10:11:59 volumio-box kernel: Feb 14 10:11:59 volumio-box kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba104000 dma = 0xfa104000 len=9024 Feb 14 10:11:59 volumio-box kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 10:11:59 volumio-box kernel: dwc_otg: Microframe scheduler enabled Feb 14 10:11:59 volumio-box kernel: Feb 14 10:11:59 volumio-box kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 10:11:59 volumio-box kernel: Feb 14 10:11:59 volumio-box kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cc05c length 36 Feb 14 10:11:59 volumio-box kernel: Feb 14 10:11:59 volumio-box kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Feb 14 10:11:59 volumio-box kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 10:11:59 volumio-box kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 volumio-box kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Feb 14 10:11:59 volumio-box kernel: Init: Port Power? op_state=1 Feb 14 10:11:59 volumio-box kernel: Init: Power Port (0) Feb 14 10:11:59 volumio-box kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Feb 14 10:11:59 volumio-box kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:11:59 volumio-box kernel: usb usb1: Product: DWC OTG Controller Feb 14 10:11:59 volumio-box kernel: usb usb1: Manufacturer: Linux 5.10.92-v7+ dwc_otg_hcd Feb 14 10:11:59 volumio-box kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 10:11:59 volumio-box kernel: hub 1-0:1.0: USB hub found Feb 14 10:11:59 volumio-box kernel: hub 1-0:1.0: 1 port detected Feb 14 10:11:59 volumio-box kernel: dwc_otg: FIQ enabled Feb 14 10:11:59 volumio-box kernel: dwc_otg: NAK holdoff enabled Feb 14 10:11:59 volumio-box kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 10:11:59 volumio-box kernel: Module dwc_common_port init Feb 14 10:11:59 volumio-box kernel: usbcore: registered new interface driver usb-storage Feb 14 10:11:59 volumio-box kernel: mousedev: PS/2 mouse device common for all mice Feb 14 10:11:59 volumio-box kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 10:11:59 volumio-box kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 10:11:59 volumio-box kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 10:11:59 volumio-box kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Feb 14 10:11:59 volumio-box kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Feb 14 10:11:59 volumio-box kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 10:11:59 volumio-box kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 10:11:59 volumio-box kernel: hid: raw HID events driver (C) Jiri Kosina Feb 14 10:11:59 volumio-box kernel: usbcore: registered new interface driver usbhid Feb 14 10:11:59 volumio-box kernel: usbhid: USB HID core driver Feb 14 10:11:59 volumio-box kernel: Initializing XFRM netlink socket Feb 14 10:11:59 volumio-box kernel: NET: Registered protocol family 17 Feb 14 10:11:59 volumio-box kernel: Key type dns_resolver registered Feb 14 10:11:59 volumio-box kernel: Registering SWP/SWPB emulation handler Feb 14 10:11:59 volumio-box kernel: registered taskstats version 1 Feb 14 10:11:59 volumio-box kernel: Loading compiled-in X.509 certificates Feb 14 10:11:59 volumio-box kernel: Key type ._fscrypt registered Feb 14 10:11:59 volumio-box kernel: Key type .fscrypt registered Feb 14 10:11:59 volumio-box kernel: Key type fscrypt-provisioning registered Feb 14 10:11:59 volumio-box kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 10:11:59 volumio-box kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Feb 14 10:11:59 volumio-box kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 10:11:59 volumio-box kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 10:11:59 volumio-box kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Feb 14 10:11:59 volumio-box kernel: sdhost: log_buf @ (ptrval) (fa107000) Feb 14 10:11:59 volumio-box kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Feb 14 10:11:59 volumio-box kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 14 10:11:59 volumio-box kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 14 10:11:59 volumio-box kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Feb 14 10:11:59 volumio-box kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 10:11:59 volumio-box kernel: of_cfs_init Feb 14 10:11:59 volumio-box kernel: of_cfs_init: OK Feb 14 10:11:59 volumio-box kernel: Indeed it is in host mode hprt0 = 00021501 Feb 14 10:11:59 volumio-box kernel: Freeing unused kernel memory: 1024K Feb 14 10:11:59 volumio-box kernel: Run /init as init process Feb 14 10:11:59 volumio-box kernel: with arguments: Feb 14 10:11:59 volumio-box kernel: /init Feb 14 10:11:59 volumio-box kernel: splash Feb 14 10:11:59 volumio-box kernel: with environment: Feb 14 10:11:59 volumio-box kernel: HOME=/ Feb 14 10:11:59 volumio-box kernel: TERM=linux Feb 14 10:11:59 volumio-box kernel: imgpart=/dev/mmcblk0p2 Feb 14 10:11:59 volumio-box kernel: imgfile=/volumio_current.sqsh Feb 14 10:11:59 volumio-box kernel: bootdelay=5 Feb 14 10:11:59 volumio-box kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 10:11:59 volumio-box kernel: mmc0: new high speed SDHC card at address 59b4 Feb 14 10:11:59 volumio-box kernel: mmcblk0: mmc0:59b4 SD 29.4 GiB Feb 14 10:11:59 volumio-box kernel: mmcblk0: p1 p2 p3 Feb 14 10:11:59 volumio-box kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 10:11:59 volumio-box kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 14 10:11:59 volumio-box kernel: Indeed it is in host mode hprt0 = 00001101 Feb 14 10:11:59 volumio-box initramfs: Booting Volumio for BCM2835 Feb 14 10:11:59 volumio-box initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 14 10:11:59 volumio-box kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 10:11:59 volumio-box initramfs: IMGPART=/dev/mmcblk0p2 Feb 14 10:11:59 volumio-box initramfs: IMGFILE=/volumio_current.sqsh Feb 14 10:11:59 volumio-box initramfs: Boot delay (except first time) will be 5 seconds Feb 14 10:11:59 volumio-box initramfs: /dev/mmcblk0p2: Feb 14 10:11:59 volumio-box kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Feb 14 10:11:59 volumio-box kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 volumio-box kernel: hub 1-1:1.0: USB hub found Feb 14 10:11:59 volumio-box kernel: hub 1-1:1.0: 5 ports detected Feb 14 10:11:59 volumio-box kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 10:11:59 volumio-box kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:11:59 volumio-box initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 14 10:11:59 volumio-box kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 14 10:11:59 volumio-box kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Feb 14 10:11:59 volumio-box kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 volumio-box kernel: smsc95xx v2.0.0 Feb 14 10:11:59 volumio-box kernel: libphy: smsc95xx-mdiobus: probed Feb 14 10:11:59 volumio-box kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:1f:5f:7d Feb 14 10:11:59 volumio-box initramfs: Checking for USB updates Feb 14 10:11:59 volumio-box initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 14 10:11:59 volumio-box kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 14 10:11:59 volumio-box kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 14 10:11:59 volumio-box kernel: EXT4-fs (mmcblk0p3): warning: mounting fs with errors, running e2fsck is recommended Feb 14 10:11:59 volumio-box kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 10:11:59 volumio-box kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:11:59 volumio-box initramfs: With Option: Feb 14 10:11:59 volumio-box initramfs: VOLUMIO_VERSION="3.378" Feb 14 10:11:59 volumio-box initramfs: Finish initramfs, continue booting Volumio Feb 14 10:11:59 volumio-box systemd[1]: System time before build time, advancing clock. Feb 14 10:11:59 volumio-box kernel: NET: Registered protocol family 10 Feb 14 10:11:59 volumio-box kernel: Segment Routing with IPv6 Feb 14 10:11:59 volumio-box 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) Feb 14 10:11:59 volumio-box systemd[1]: Detected architecture arm. Feb 14 10:11:59 volumio-box systemd[1]: Set hostname to . Feb 14 10:11:59 volumio-box 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. Feb 14 10:11:59 volumio-box kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 volumio-box kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 volumio-box kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 volumio-box systemd[1]: Started Forward Password Requests to Wall Directory Watch. Feb 14 10:11:59 volumio-box systemd[1]: Listening on RPCbind Server Activation Socket. Feb 14 10:11:59 volumio-box systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Feb 14 10:11:59 volumio-box systemd[1]: Listening on udev Control Socket. Feb 14 10:11:59 volumio-box systemd[1]: Created slice system-systemd\x2dfsck.slice. Feb 14 10:11:59 volumio-box systemd[1]: Listening on Journal Audit Socket. Feb 14 10:11:59 volumio-box kernel: i2c /dev entries driver Feb 14 10:11:59 volumio-box systemd-journald[193]: Journal started Feb 14 10:11:59 volumio-box systemd-journald[193]: Runtime journal (/run/log/journal/6c9b1d414d94da048f39d3e06336abc2) is 3.7M, max 30.0M, 26.2M free. Feb 14 10:11:59 volumio-box systemd-modules-load[190]: Inserted module 'i2c_dev' Feb 14 10:11:59 volumio-box fake-hwclock[184]: date: invalid date ‘g1\262#o10!%+\021t\020)42\262\2717\v’ Feb 14 10:12:00 volumio-box fake-hwclock[184]: /sbin/fake-hwclock: 53: [: -le: argument expected Feb 14 10:12:00 volumio-box fake-hwclock[184]: Current system time: 2019-02-14 10:11:59 Feb 14 10:12:00 volumio-box fake-hwclock[184]: [73B blob data] Feb 14 10:12:00 volumio-box fake-hwclock[184]: To set system time to this saved clock anyway, use "force" Feb 14 10:11:59 volumio-box systemd[1]: Started Apply Kernel Variables. Feb 14 10:12:00 volumio-box systemd[1]: Started Load/Save Random Seed. Feb 14 10:12:00 volumio-box systemd[1]: dynamicswap.service: Succeeded. Feb 14 10:12:00 volumio-box systemd[1]: Started Create System Users. Feb 14 10:12:00 volumio-box systemd[1]: Starting Create Static Device Nodes in /dev... Feb 14 10:12:00 volumio-box systemd[1]: Started Create Static Device Nodes in /dev. Feb 14 10:12:00 volumio-box systemd[1]: Reached target Local File Systems (Pre). Feb 14 10:12:00 volumio-box systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 14 10:12:00 volumio-box systemd[1]: Mounting /tmp... Feb 14 10:12:00 volumio-box systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 14 10:12:00 volumio-box systemd[1]: Mounting /var/log... Feb 14 10:12:00 volumio-box systemd[1]: Mounting /var/spool/cups... Feb 14 10:12:00 volumio-box systemd[1]: Starting udev Kernel Device Manager... Feb 14 10:12:00 volumio-box systemd[1]: Mounted /tmp. Feb 14 10:12:00 volumio-box systemd[1]: Mounted /var/log. Feb 14 10:12:00 volumio-box systemd[1]: Starting Flush Journal to Persistent Storage... Feb 14 10:12:00 volumio-box systemd[1]: Started udev Coldplug all Devices. Feb 14 10:12:00 volumio-box systemd[1]: Mounted /var/spool/cups. Feb 14 10:12:00 volumio-box systemd[1]: Mounting /var/spool/cups/tmp... Feb 14 10:12:00 volumio-box systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 14 10:12:00 volumio-box systemd-udevd[214]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 14 10:12:00 volumio-box systemd[1]: Mounted /var/spool/cups/tmp. Feb 14 10:12:00 volumio-box systemd-journald[193]: Runtime journal (/run/log/journal/6c9b1d414d94da048f39d3e06336abc2) is 3.7M, max 30.0M, 26.2M free. Feb 14 10:12:00 volumio-box systemd[1]: Started Flush Journal to Persistent Storage. Feb 14 10:12:00 volumio-box systemd[1]: Started udev Kernel Device Manager. Feb 14 10:12:00 volumio-box systemd[1]: Starting Show Plymouth Boot Screen... Feb 14 10:12:00 volumio-box systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd). Feb 14 10:12:00 volumio-box systemd[1]: Started Show Plymouth Boot Screen. Feb 14 10:12:00 volumio-box systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 14 10:12:00 volumio-box systemd[1]: Reached target Paths. Feb 14 10:12:00 volumio-box systemd[1]: Reached target Local Encrypted Volumes. Feb 14 10:12:00 volumio-box systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 14 10:12:00 volumio-box kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 10:12:00 volumio-box kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 14 10:12:00 volumio-box kernel: [vc_sm_connected_init]: start Feb 14 10:12:00 volumio-box kernel: [vc_sm_connected_init]: installed successfully Feb 14 10:12:00 volumio-box kernel: mc: Linux media interface: v0.10 Feb 14 10:12:01 volumio-box kernel: videodev: Linux video capture interface: v2.00 Feb 14 10:12:01 volumio-box kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 10:12:01 volumio-box kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 10:12:01 volumio-box kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 10:12:01 volumio-box kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Feb 14 10:12:01 volumio-box kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 14 10:12:01 volumio-box kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Feb 14 10:12:01 volumio-box kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 10:12:01 volumio-box kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 14 10:12:01 volumio-box kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 14 10:12:01 volumio-box systemd[1]: Found device /dev/mmcblk0p1. Feb 14 10:12:01 volumio-box systemd-udevd[231]: Using default interface naming scheme 'v240'. Feb 14 10:12:01 volumio-box systemd-udevd[226]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 10:12:01 volumio-box systemd-udevd[231]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 10:12:02 volumio-box kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 14 10:12:02 volumio-box kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 14 10:12:02 volumio-box kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Feb 14 10:12:02 volumio-box kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Feb 14 10:12:02 volumio-box kernel: usbcore: registered new interface driver brcmfmac Feb 14 10:12:03 volumio-box kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Feb 14 10:12:03 volumio-box kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Feb 14 10:12:03 volumio-box kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Feb 14 10:12:03 volumio-box 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 Feb 14 10:12:03 volumio-box systemd-udevd[228]: Using default interface naming scheme 'v240'. Feb 14 10:12:03 volumio-box systemd-udevd[228]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 10:12:04 volumio-box systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in FUSE Control File System being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Starting File System Check on /dev/mmcblk0p1... Feb 14 10:12:04 volumio-box systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 14 10:12:04 volumio-box systemd[1]: Started File System Check Daemon to report status. Feb 14 10:12:04 volumio-box systemd[1]: Started Load/Save RF Kill Switch Status. Feb 14 10:12:04 volumio-box systemd-fsck[391]: fsck.fat 4.1 (2017-01-24) Feb 14 10:12:04 volumio-box systemd-fsck[391]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Feb 14 10:12:04 volumio-box systemd-fsck[391]: Automatically removing dirty bit. Feb 14 10:12:04 volumio-box systemd-fsck[391]: Performing changes. Feb 14 10:12:04 volumio-box systemd-fsck[391]: /dev/mmcblk0p1: 297 files, 27486/46774 clusters Feb 14 10:12:04 volumio-box systemd[1]: Started File System Check on /dev/mmcblk0p1. Feb 14 10:12:04 volumio-box systemd[1]: Mounting /boot... Feb 14 10:12:04 volumio-box systemd[1]: Mounted /boot. Feb 14 10:12:04 volumio-box systemd[1]: Reached target Local File Systems. Feb 14 10:12:04 volumio-box systemd[1]: Started ifup for eth0. Feb 14 10:12:04 volumio-box systemd[1]: Starting Create Volatile Files and Directories... Feb 14 10:12:04 volumio-box systemd[1]: Starting Preprocess NFS configuration... Feb 14 10:12:04 volumio-box systemd[1]: Started ifup for wlan0. Feb 14 10:12:04 volumio-box systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Starting Raise network interfaces... Feb 14 10:12:04 volumio-box systemd[1]: plymouth-read-write.service: Succeeded. Feb 14 10:12:04 volumio-box systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 14 10:12:04 volumio-box systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd). Feb 14 10:12:04 volumio-box systemd[1]: nfs-config.service: Succeeded. Feb 14 10:12:04 volumio-box systemd[1]: Started Preprocess NFS configuration. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Reached target NFS client services. Feb 14 10:12:04 volumio-box systemd[1]: Started Create Volatile Files and Directories. Feb 14 10:12:04 volumio-box systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 14 10:12:04 volumio-box systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 14 10:12:04 volumio-box systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 14 10:12:04 volumio-box systemd[1]: Starting RPC bind portmap service... Feb 14 10:12:05 volumio-box systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 14 10:12:05 volumio-box systemd[1]: Reached target System Initialization. Feb 14 10:12:05 volumio-box systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 14 10:12:05 volumio-box systemd[1]: apt-daily.timer: Not using persistent file timestamp Thu 2024-05-02 13:46:24 UTC as it is in the future. Feb 14 10:12:05 volumio-box systemd[1]: Started Daily apt download activities. Feb 14 10:12:05 volumio-box kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 14 10:12:05 volumio-box systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Thu 2024-05-02 06:38:42 UTC as it is in the future. Feb 14 10:12:05 volumio-box systemd[1]: Started Daily apt upgrade and clean activities. Feb 14 10:12:05 volumio-box systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 14 10:12:05 volumio-box systemd[1]: Reached target Timers. Feb 14 10:12:05 volumio-box systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 14 10:12:05 volumio-box kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 10:12:05 volumio-box systemd[1]: Listening on mpd.socket. Feb 14 10:12:05 volumio-box systemd[1]: Listening on triggerhappy.socket. Feb 14 10:12:05 volumio-box systemd[1]: Reached target Sockets. Feb 14 10:12:05 volumio-box systemd[1]: Reached target Basic System. Feb 14 10:12:05 volumio-box systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 14 10:12:05 volumio-box systemd[1]: Started Manage Sound Card State (restore and store). Feb 14 10:12:05 volumio-box systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 14 10:12:05 volumio-box systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 14 10:12:05 volumio-box systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 14 10:12:05 volumio-box alsactl[475]: alsactl 1.1.8 daemon started Feb 14 10:12:05 volumio-box systemd[1]: Starting dhcpcd on all interfaces... Feb 14 10:12:05 volumio-box systemd[1]: Started Volumio Iptables Module. Feb 14 10:12:05 volumio-box systemd[1]: Started volumio-remote-updater.service. Feb 14 10:12:05 volumio-box systemd[1]: Starting Wireless Services... Feb 14 10:12:05 volumio-box systemd[1]: Started D-Bus System Message Bus. Feb 14 10:12:05 volumio-box dhcpcd[481]: Not running dhcpcd because /etc/network/interfaces Feb 14 10:12:05 volumio-box dhcpcd[481]: defines some interfaces that will use a Feb 14 10:12:05 volumio-box dhcpcd[481]: DHCP client or static address Feb 14 10:12:05 volumio-box systemd[1]: Starting Login Service... Feb 14 10:12:05 volumio-box systemd[1]: Starting Save/Restore Sound Card State... Feb 14 10:12:05 volumio-box systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 14 10:12:05 volumio-box systemd[1]: Started UPnP Renderer front-end to MPD. Feb 14 10:12:05 volumio-box systemd[1]: Starting triggerhappy global hotkey daemon... Feb 14 10:12:05 volumio-box systemd[1]: Starting WPA supplicant... Feb 14 10:12:05 volumio-box systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 14 10:12:05 volumio-box systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 14 10:12:05 volumio-box systemd[1]: Failed to start dhcpcd on all interfaces. Feb 14 10:12:05 volumio-box avahi-daemon[474]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 14 10:12:05 volumio-box avahi-daemon[474]: Successfully dropped root privileges. Feb 14 10:12:05 volumio-box avahi-daemon[474]: avahi-daemon 0.7 starting up. Feb 14 10:12:05 volumio-box thd[528]: Unable to parse trigger line: Feb 14 10:12:05 volumio-box thd[528]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 14 10:12:05 volumio-box thd[528]: Unable to parse trigger line: Feb 14 10:12:05 volumio-box thd[528]: Unable to parse trigger line: Feb 14 10:12:05 volumio-box systemd[1]: Started Save/Restore Sound Card State. Feb 14 10:12:05 volumio-box systemd[1]: Reached target Sound Card. Feb 14 10:12:05 volumio-box ifup[407]: ifup: waiting for lock on /run/network/ifstate.wlan0 Feb 14 10:12:05 volumio-box sh[401]: wlan0=wlan0 Feb 14 10:12:05 volumio-box sh[397]: eth0: waiting for carrier Feb 14 10:12:05 volumio-box dhcpcd[447]: eth0: waiting for carrier Feb 14 10:12:05 volumio-box kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL) Feb 14 10:12:05 volumio-box kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Feb 14 10:12:05 volumio-box kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Feb 14 10:12:06 volumio-box kernel: uart-pl011 3f201000.serial: no DMA platform data Feb 14 10:12:06 volumio-box systemd-logind[523]: New seat seat0. Feb 14 10:12:06 volumio-box thd[528]: Found socket passed from systemd Feb 14 10:12:06 volumio-box systemd[1]: Started RPC bind portmap service. Feb 14 10:12:06 volumio-box systemd[1]: Started triggerhappy global hotkey daemon. Feb 14 10:12:06 volumio-box avahi-daemon[474]: Successfully called chroot(). Feb 14 10:12:06 volumio-box avahi-daemon[474]: Successfully dropped remaining capabilities. Feb 14 10:12:06 volumio-box avahi-daemon[474]: Loading service file /services/volumio.service. Feb 14 10:12:06 volumio-box avahi-daemon[474]: Network interface enumeration completed. Feb 14 10:12:06 volumio-box systemd[1]: Started Raise network interfaces. Feb 14 10:12:06 volumio-box systemd[1]: Started Login Service. Feb 14 10:12:06 volumio-box systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 14 10:12:06 volumio-box systemd[1]: Reached target RPC Port Mapper. Feb 14 10:12:06 volumio-box systemd[1]: Reached target Remote File Systems (Pre). Feb 14 10:12:06 volumio-box systemd[1]: Reached target Remote File Systems. Feb 14 10:12:06 volumio-box avahi-daemon[474]: Server startup complete. Host name is volumio-box.local. Local service cookie is 692056116. Feb 14 10:12:06 volumio-box avahi-daemon[474]: Service "Volumio Box" (/services/volumio.service) successfully established. Feb 14 10:12:06 volumio-box systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 14 10:12:07 volumio-box loadcpufreq[572]: Loading cpufreq kernel modules...done (none). Feb 14 10:12:07 volumio-box systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 14 10:12:07 volumio-box systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 14 10:12:07 volumio-box haveged[451]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 14 10:12:07 volumio-box haveged[451]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 14 10:12:07 volumio-box haveged[451]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99921 Feb 14 10:12:07 volumio-box haveged[451]: haveged: fills: 0, generated: 0 Feb 14 10:12:07 volumio-box systemd[1]: Started WPA supplicant. Feb 14 10:12:07 volumio-box systemd[1]: Reached target Network. Feb 14 10:12:07 volumio-box kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Feb 14 10:12:07 volumio-box sh[397]: eth0: carrier acquired Feb 14 10:12:07 volumio-box dhcpcd[447]: eth0: carrier acquired Feb 14 10:12:07 volumio-box wpa_supplicant[532]: Successfully initialized wpa_supplicant Feb 14 10:12:07 volumio-box kernel: random: crng init done Feb 14 10:12:07 volumio-box kernel: random: 7 urandom warning(s) missed due to ratelimiting Feb 14 10:12:07 volumio-box systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 14 10:12:07 volumio-box sh[397]: DUID 00:01:00:01:23:f7:fb:79:b8:27:eb:1f:5f:7d Feb 14 10:12:07 volumio-box sh[397]: eth0: IAID eb:1f:5f:7d Feb 14 10:12:07 volumio-box sh[397]: eth0: adding address fe80::ba27:ebff:fe1f:5f7d Feb 14 10:12:07 volumio-box sh[397]: ipv6_addaddr1: Permission denied Feb 14 10:12:07 volumio-box dhcpcd[447]: DUID 00:01:00:01:23:f7:fb:79:b8:27:eb:1f:5f:7d Feb 14 10:12:07 volumio-box dhcpcd[447]: eth0: IAID eb:1f:5f:7d Feb 14 10:12:07 volumio-box dhcpcd[447]: eth0: adding address fe80::ba27:ebff:fe1f:5f7d Feb 14 10:12:07 volumio-box dhcpcd[447]: ipv6_addaddr1: Permission denied Feb 14 10:12:07 volumio-box cpufrequtils[603]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 14 10:12:07 volumio-box systemd[1]: Starting Music Player Daemon... Feb 14 10:12:07 volumio-box systemd[1]: Reached target Network is Online. Feb 14 10:12:07 volumio-box systemd[1]: Starting LSB: Brings up/down network automatically... Feb 14 10:12:07 volumio-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:08 volumio-box systemd[1]: Starting Samba NMB Daemon... Feb 14 10:12:08 volumio-box systemd[1]: Starting /etc/rc.local Compatibility... Feb 14 10:12:08 volumio-box systemd[1]: Starting Network Time Service... Feb 14 10:12:08 volumio-box sh[397]: eth0: open `/var/lib/dhcpcd5/eth0.lease': Structure needs cleaning Feb 14 10:12:08 volumio-box sh[397]: eth0: soliciting a DHCP lease Feb 14 10:12:08 volumio-box dhcpcd[447]: eth0: open `/var/lib/dhcpcd5/eth0.lease': Structure needs cleaning Feb 14 10:12:08 volumio-box dhcpcd[447]: eth0: soliciting a DHCP lease Feb 14 10:12:08 volumio-box kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1706: inode #1180262: comm dhcpcd: iget: bad extra_isize 39798 (inode size 256) Feb 14 10:12:08 volumio-box systemd[1]: Starting OpenBSD Secure Shell server... Feb 14 10:12:08 volumio-box systemd[1]: Starting Permit User Sessions... Feb 14 10:12:08 volumio-box systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 14 10:12:08 volumio-box systemd[1]: Started /etc/rc.local Compatibility. Feb 14 10:12:08 volumio-box systemd[1]: Started Permit User Sessions. Feb 14 10:12:08 volumio-box systemd[1]: iptables.service: Succeeded. Feb 14 10:12:08 volumio-box systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 14 10:12:08 volumio-box systemd[1]: Starting Hold until boot process finishes up... Feb 14 10:12:08 volumio-box systemd[1]: plymouth-start.service: Succeeded. Feb 14 10:12:08 volumio-box systemd[1]: plymouth-quit.service: Succeeded. Feb 14 10:12:08 volumio-box systemd[1]: Started Terminate Plymouth Boot Screen. Feb 14 10:12:08 volumio-box systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 14 10:12:08 volumio-box systemd[1]: Started Hold until boot process finishes up. Feb 14 10:12:08 volumio-box systemd[1]: Received SIGRTMIN+21 from PID 232 (n/a). Feb 14 10:12:08 volumio-box systemd[1]: Received SIGRTMIN+21 from PID 232 (n/a). Feb 14 10:12:08 volumio-box systemd[1]: Started Getty on tty1. Feb 14 10:12:08 volumio-box systemd[1]: Reached target Login Prompts. Feb 14 10:12:08 volumio-box ifplugd(eth0)[653]: ifplugd 0.28 initializing. Feb 14 10:12:08 volumio-box ifplugd(eth0)[653]: Using interface eth0/B8:27:EB:1F:5F:7D with driver (version: 5.10.92-v7+) Feb 14 10:12:08 volumio-box ifplugd(eth0)[653]: Using detection mode: SIOCETHTOOL Feb 14 10:12:08 volumio-box ifplugd(eth0)[653]: Initialization complete, link beat detected. Feb 14 10:12:08 volumio-box sh[397]: eth0: soliciting an IPv6 router Feb 14 10:12:08 volumio-box dhcpcd[447]: eth0: soliciting an IPv6 router Feb 14 10:12:08 volumio-box ifplugd(eth0)[653]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 14 10:12:08 volumio-box ntpd[627]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 14 10:12:08 volumio-box ntpd[627]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 14 10:12:08 volumio-box systemd[1]: Started Network Time Service. Feb 14 10:12:08 volumio-box sh[397]: eth0: offered 192.168.1.28 from 192.168.1.254 `Experiabox-V10' Feb 14 10:12:08 volumio-box dhcpcd[447]: eth0: offered 192.168.1.28 from 192.168.1.254 `Experiabox-V10' Feb 14 10:12:08 volumio-box sh[397]: eth0: probing address 192.168.1.28/24 Feb 14 10:12:08 volumio-box dhcpcd[447]: eth0: probing address 192.168.1.28/24 Feb 14 10:12:08 volumio-box ifplugd(eth0)[653]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 14 10:12:08 volumio-box ntpd[656]: proto: precision = 0.677 usec (-20) Feb 14 10:12:08 volumio-box ntpd[656]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 10:12:08 volumio-box ntpd[656]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 10:12:08 volumio-box ntpd[656]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 10:12:08 volumio-box ntpd[656]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 10:12:08 volumio-box ntpd[656]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 10:12:08 volumio-box ntpd[656]: Listening on routing socket on fd #19 for interface updates Feb 14 10:12:08 volumio-box ntpd[656]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 10:12:08 volumio-box ntpd[656]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 10:12:09 volumio-box systemd[1]: systemd-rfkill.service: Succeeded. Feb 14 10:12:09 volumio-box sshd[633]: Error loading host key "/etc/ssh/ssh_host_dsa_key": invalid format Feb 14 10:12:09 volumio-box sshd[633]: Error loading host key "/etc/ssh/ssh_host_dsa_key": invalid format Feb 14 10:12:09 volumio-box sshd[633]: Could not load host key: /etc/ssh/ssh_host_dsa_key Feb 14 10:12:09 volumio-box sshd[633]: Error loading host key "/etc/ssh/ssh_host_ecdsa_key": invalid format Feb 14 10:12:09 volumio-box volumio-remote-updater[488]: Error: No active session Feb 14 10:12:09 volumio-box sshd[633]: Error loading host key "/etc/ssh/ssh_host_ecdsa_key": invalid format Feb 14 10:12:09 volumio-box sshd[633]: Could not load host key: /etc/ssh/ssh_host_ecdsa_key Feb 14 10:12:09 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:09] [info] asio async_connect error: system:111 (Connection refused) Feb 14 10:12:09 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 10:12:09 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:09] [error] handle_connect error: Underlying Transport Error Feb 14 10:12:09 volumio-box ntpd[656]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 10:12:09 volumio-box sshd[666]: Error loading host key "/etc/ssh/ssh_host_dsa_key": invalid format Feb 14 10:12:09 volumio-box sshd[666]: Error loading host key "/etc/ssh/ssh_host_dsa_key": invalid format Feb 14 10:12:09 volumio-box sshd[666]: Could not load host key: /etc/ssh/ssh_host_dsa_key Feb 14 10:12:09 volumio-box sshd[666]: Error loading host key "/etc/ssh/ssh_host_ecdsa_key": invalid format Feb 14 10:12:09 volumio-box sshd[666]: Could not load host key: /etc/ssh/ssh_host_ecdsa_key Feb 14 10:12:09 volumio-box sshd[666]: Server listening on 0.0.0.0 port 22. Feb 14 10:12:09 volumio-box sshd[666]: Server listening on :: port 22. Feb 14 10:12:09 volumio-box systemd[1]: Started OpenBSD Secure Shell server. Feb 14 10:12:09 volumio-box volumio[527]: Could not open config: /tmp/upmpdcli.conf Feb 14 10:12:09 volumio-box systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 14 10:12:09 volumio-box systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 14 10:12:10 volumio-box ntpd[656]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 10:12:11 volumio-box kernel: Bluetooth: Core ver 2.22 Feb 14 10:12:11 volumio-box kernel: NET: Registered protocol family 31 Feb 14 10:12:11 volumio-box kernel: Bluetooth: HCI device and connection manager initialized Feb 14 10:12:11 volumio-box kernel: Bluetooth: HCI socket layer initialized Feb 14 10:12:11 volumio-box kernel: Bluetooth: L2CAP socket layer initialized Feb 14 10:12:11 volumio-box kernel: Bluetooth: SCO socket layer initialized Feb 14 10:12:11 volumio-box kernel: Bluetooth: HCI UART driver ver 2.3 Feb 14 10:12:11 volumio-box kernel: Bluetooth: HCI UART protocol H4 registered Feb 14 10:12:11 volumio-box kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 14 10:12:11 volumio-box kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 14 10:12:11 volumio-box btuart[477]: bcm43xx_init Feb 14 10:12:11 volumio-box btuart[477]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Feb 14 10:12:11 volumio-box btuart[477]: Set BDADDR UART: b8:27:eb:b5:f5:d7 Feb 14 10:12:11 volumio-box btuart[477]: Set Controller UART speed to 460800 bit/s Feb 14 10:12:11 volumio-box btuart[477]: Device setup complete Feb 14 10:12:11 volumio-box systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 14 10:12:11 volumio-box systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 14 10:12:11 volumio-box ntpd[656]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 10:12:11 volumio-box systemd-rfkill[675]: Failed to parse state file /var/lib/systemd/rfkill/platform-soc:bluetooth: Invalid argument Feb 14 10:12:11 volumio-box systemd[1]: Started Load/Save RF Kill Switch Status. Feb 14 10:12:11 volumio-box systemd[1]: Created slice system-bthelper.slice. Feb 14 10:12:11 volumio-box systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 14 10:12:11 volumio-box kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1706: inode #1180807: comm nmbd: iget: bad extra_isize 10240 (inode size 256) Feb 14 10:12:11 volumio-box kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1706: inode #1180807: comm nmbd: iget: bad extra_isize 10240 (inode size 256) Feb 14 10:12:12 volumio-box bthelper[678]: Raspberry Pi BDADDR already set Feb 14 10:12:12 volumio-box systemd[1]: Started Raspberry Pi bluetooth helper. Feb 14 10:12:12 volumio-box systemd[1]: Starting Bluetooth service... Feb 14 10:12:12 volumio-box systemd[1]: nmbd.service: Main process exited, code=exited, status=1/FAILURE Feb 14 10:12:12 volumio-box systemd[1]: nmbd.service: Failed with result 'exit-code'. Feb 14 10:12:12 volumio-box systemd[1]: Failed to start Samba NMB Daemon. Feb 14 10:12:12 volumio-box systemd[1]: Starting Samba Winbind Daemon... Feb 14 10:12:12 volumio-box bluetoothd[687]: Bluetooth daemon 5.50 Feb 14 10:12:12 volumio-box bluetoothd[687]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 14 10:12:12 volumio-box systemd[1]: Started Bluetooth service. Feb 14 10:12:12 volumio-box systemd[1]: Reached target Bluetooth. Feb 14 10:12:12 volumio-box bluetoothd[687]: Starting SDP server Feb 14 10:12:12 volumio-box bluetoothd[687]: Excluding (cli) sap Feb 14 10:12:12 volumio-box kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 14 10:12:12 volumio-box kernel: Bluetooth: BNEP filters: protocol multicast Feb 14 10:12:12 volumio-box kernel: Bluetooth: BNEP socket layer initialized Feb 14 10:12:12 volumio-box dbus-daemon[492]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=687 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 14 10:12:12 volumio-box bluetoothd[687]: Bluetooth management interface 1.18 initialized Feb 14 10:12:12 volumio-box ntpd[656]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 10:12:12 volumio-box systemd[1]: Starting Hostname Service... Feb 14 10:12:12 volumio-box bluetoothd[687]: Failed to set privacy: Rejected (0x0b) Feb 14 10:12:12 volumio-box dbus-daemon[492]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 14 10:12:12 volumio-box systemd[1]: Started Hostname Service. Feb 14 10:12:12 volumio-box winbindd[689]: [2019/02/14 10:12:12.920090, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 14 10:12:12 volumio-box winbindd[689]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 14 10:12:12 volumio-box winbindd[689]: [2019/02/14 10:12:12.965118, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 14 10:12:12 volumio-box winbindd[689]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 14 10:12:12 volumio-box systemd[1]: Started Samba Winbind Daemon. Feb 14 10:12:12 volumio-box systemd[1]: Starting Samba SMB Daemon... Feb 14 10:12:13 volumio-box sh[397]: eth0: leased 192.168.1.28 for 86400 seconds Feb 14 10:12:13 volumio-box dhcpcd[447]: eth0: leased 192.168.1.28 for 86400 seconds Feb 14 10:12:13 volumio-box kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1706: inode #1180262: comm dhcpcd: iget: bad extra_isize 39798 (inode size 256) Feb 14 10:12:13 volumio-box sh[397]: dhcp_bind: Structure needs cleaning Feb 14 10:12:13 volumio-box dhcpcd[447]: dhcp_bind: Structure needs cleaning Feb 14 10:12:13 volumio-box sh[397]: eth0: adding route to 192.168.1.0/24 Feb 14 10:12:13 volumio-box sh[397]: eth0: adding default route via 192.168.1.254 Feb 14 10:12:13 volumio-box dhcpcd[447]: eth0: adding route to 192.168.1.0/24 Feb 14 10:12:13 volumio-box dhcpcd[447]: eth0: adding default route via 192.168.1.254 Feb 14 10:12:13 volumio-box avahi-daemon[474]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.28. Feb 14 10:12:13 volumio-box avahi-daemon[474]: New relevant interface eth0.IPv4 for mDNS. Feb 14 10:12:13 volumio-box avahi-daemon[474]: Registering new address record for 192.168.1.28 on eth0.IPv4. Feb 14 10:12:13 volumio-box sh[397]: forked to background, child pid 718 Feb 14 10:12:13 volumio-box dhcpcd[447]: forked to background, child pid 718 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.677411, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.678273, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.679370, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.679798, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.680059, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.680212, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.680311, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Feb 14 10:12:13 volumio-box smbd[696]: tdb(/var/lib/samba/registry.tdb): tdb_rec_read bad magic 0x8ec2ff20 at offset=525972 Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.711203, 0] ../source3/registry/reg_backend_db.c:1166(regdb_store_subkey_list) Feb 14 10:12:13 volumio-box smbd[696]: regdb_store_keys: Failed to store new record for key [HKLM\SOFTWARE\Microsoft\Windows NT\CurrentVersion\Group Policy]: WERR_GEN_FAILURE Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.711555, 0] ../source3/registry/reg_init_basic.c:51(registry_init_common) Feb 14 10:12:13 volumio-box smbd[696]: Failed to initialize data in registry! Feb 14 10:12:13 volumio-box smbd[696]: [2019/02/14 10:12:13.711998, 0] ../lib/util/become_daemon.c:124(exit_daemon) Feb 14 10:12:13 volumio-box smbd[696]: exit_daemon: STATUS=daemon failed to start: Samba cannot init registry, error code 13 Feb 14 10:12:13 volumio-box ifplugd(eth0)[653]: client: ifup: interface eth0 already configured Feb 14 10:12:13 volumio-box sh[397]: eth0=eth0 Feb 14 10:12:13 volumio-box systemd[1]: smbd.service: Main process exited, code=exited, status=1/FAILURE Feb 14 10:12:13 volumio-box systemd[1]: smbd.service: Failed with result 'exit-code'. Feb 14 10:12:13 volumio-box systemd[1]: Failed to start Samba SMB Daemon. Feb 14 10:12:13 volumio-box wireless.js[489]: Cleaning previous... Feb 14 10:12:13 volumio-box ifplugd(eth0)[653]: Program executed successfully. Feb 14 10:12:13 volumio-box ifplugd[621]: Network Interface Plugging Daemon...start eth0...done. Feb 14 10:12:13 volumio-box systemd[1]: Started LSB: Brings up/down network automatically. Feb 14 10:12:14 volumio-box sudo[754]: pam_unix(sudo:account): account root has password changed in future Feb 14 10:12:14 volumio-box sudo[754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 10:12:14 volumio-box sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:14 volumio-box sudo[754]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:14 volumio-box sudo[756]: pam_unix(sudo:account): account root has password changed in future Feb 14 10:12:14 volumio-box sudo[756]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 10:12:14 volumio-box sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:14 volumio-box mpd[646]: exception: Error in /etc/mpd.conf line 1 Feb 14 10:12:14 volumio-box mpd[646]: exception: nested: Letter expected Feb 14 10:12:14 volumio-box sudo[756]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:14 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:14] [info] asio async_connect error: system:111 (Connection refused) Feb 14 10:12:14 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 10:12:14 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:14] [error] handle_connect error: Underlying Transport Error Feb 14 10:12:14 volumio-box wireless.js[489]: Stopped aP Feb 14 10:12:14 volumio-box systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 14 10:12:14 volumio-box systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 14 10:12:14 volumio-box sudo[763]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:14 volumio-box sudo[763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 10:12:14 volumio-box systemd[1]: Failed to start Music Player Daemon. Feb 14 10:12:14 volumio-box sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:14 volumio-box sudo[763]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:14 volumio-box kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 10:12:14 volumio-box sudo[765]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:14 volumio-box sudo[765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 14 10:12:14 volumio-box sudo[765]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:14 volumio-box sudo[765]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:14 volumio-box sudo[772]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:14 volumio-box sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 14 10:12:14 volumio-box sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:14 volumio-box sudo[772]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:14 volumio-box sudo[774]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:14 volumio-box sudo[774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 14 10:12:14 volumio-box sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:15 volumio-box sudo[774]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:15 volumio-box wireless.js[489]: Start wireless flow Feb 14 10:12:15 volumio-box wireless.js[489]: Stopped hotspot (if there).. Feb 14 10:12:15 volumio-box sudo[779]: pam_unix(sudo:account): account root has password changed in future Feb 14 10:12:15 volumio-box sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 10:12:15 volumio-box sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:15 volumio-box sudo[779]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:15 volumio-box sudo[781]: pam_unix(sudo:account): account root has password changed in future Feb 14 10:12:15 volumio-box sudo[781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 10:12:15 volumio-box sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:16 volumio-box sudo[781]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:16 volumio-box wireless.js[489]: DHCP IP Feb 14 10:12:16 volumio-box wireless.js[489]: Start ap Feb 14 10:12:16 volumio-box wpa_supplicant[783]: Successfully initialized wpa_supplicant Feb 14 10:12:16 volumio-box sudo[784]: pam_unix(sudo:account): account root has password changed in future Feb 14 10:12:16 volumio-box sudo[784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 14 10:12:16 volumio-box sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:16 volumio-box dhcpcd[785]: dev: loaded udev Feb 14 10:12:16 volumio-box kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 10:12:16 volumio-box dhcpcd-run-hooks[794]: wlan0: starting wpa_supplicant Feb 14 10:12:16 volumio-box dhcpcd-run-hooks[799]: wlan0: failed to start wpa_supplicant Feb 14 10:12:16 volumio-box dhcpcd-run-hooks[800]: 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 Feb 14 10:12:16 volumio-box dhcpcd[785]: wlan0: connected to Access Point `' Feb 14 10:12:16 volumio-box dhcpcd[785]: DUID 00:01:00:01:23:f7:fb:79:b8:27:eb:1f:5f:7d Feb 14 10:12:16 volumio-box dhcpcd[785]: eth0: IAID eb:1f:5f:7d Feb 14 10:12:16 volumio-box dhcpcd[785]: eth0: adding address fe80::ba27:ebff:fe1f:5f7d Feb 14 10:12:16 volumio-box dhcpcd[785]: ipv6_addaddr1: Permission denied Feb 14 10:12:16 volumio-box dhcpcd[785]: wlan0: waiting for carrier Feb 14 10:12:16 volumio-box systemd[1]: systemd-rfkill.service: Succeeded. Feb 14 10:12:16 volumio-box wpa_supplicant[798]: wlan0: Trying to associate with SSID 'ASUS24' Feb 14 10:12:17 volumio-box bthelper[678]: Changing power off succeeded Feb 14 10:12:17 volumio-box bthelper[678]: [CHG] Controller B8:27:EB:B5:F5:D7 Powered: no Feb 14 10:12:17 volumio-box bthelper[678]: [CHG] Controller B8:27:EB:B5:F5:D7 Discovering: no Feb 14 10:12:17 volumio-box bthelper[678]: [CHG] Controller B8:27:EB:B5:F5:D7 Class: 0x00000000 Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: soliciting an IPv6 router Feb 14 10:12:17 volumio-box kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1706: inode #1180262: comm dhcpcd: iget: bad extra_isize 39798 (inode size 256) Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: open `/var/lib/dhcpcd5/eth0.lease': Structure needs cleaning Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: soliciting a DHCP lease Feb 14 10:12:17 volumio-box wireless.js[489]: trying... Feb 14 10:12:17 volumio-box sudo[820]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:17 volumio-box sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:17 volumio-box sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:17 volumio-box sudo[820]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: offered 192.168.1.28 from 192.168.1.254 `Experiabox-V10' Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: leased 192.168.1.28 for 86400 seconds Feb 14 10:12:17 volumio-box dhcpcd[785]: dhcp_bind: Structure needs cleaning Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: adding route to 192.168.1.0/24 Feb 14 10:12:17 volumio-box dhcpcd[785]: eth0: adding default route via 192.168.1.254 Feb 14 10:12:17 volumio-box kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1706: inode #1180262: comm dhcpcd: iget: bad extra_isize 39798 (inode size 256) Feb 14 10:12:17 volumio-box bthelper[678]: [CHG] Controller B8:27:EB:B5:F5:D7 Class: 0x0000041c Feb 14 10:12:17 volumio-box bthelper[678]: Changing power on succeeded Feb 14 10:12:17 volumio-box dhcpcd[785]: forked to background, child pid 849 Feb 14 10:12:17 volumio-box sudo[784]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:17 volumio-box wpa_supplicant[798]: wlan0: Associated with f0:79:59:77:0a:58 Feb 14 10:12:17 volumio-box wpa_supplicant[798]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:79:59:77:0a:58 completed [id=0 id_str=] Feb 14 10:12:17 volumio-box wpa_supplicant[798]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 14 10:12:17 volumio-box dhcpcd[849]: wlan0: carrier acquired Feb 14 10:12:17 volumio-box dhcpcd[849]: wlan0: connected to Access Point `ASUS24' Feb 14 10:12:17 volumio-box dhcpcd[849]: wlan0: IAID eb:4a:0a:28 Feb 14 10:12:17 volumio-box dhcpcd[849]: wlan0: adding address fe80::ba27:ebff:fe4a:a28 Feb 14 10:12:17 volumio-box dhcpcd[849]: ipv6_addaddr1: Permission denied Feb 14 10:12:17 volumio-box dhcpcd[849]: wlan0: probing address 192.168.1.181/24 Feb 14 10:12:18 volumio-box wireless.js[489]: trying... Feb 14 10:12:18 volumio-box sudo[852]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:18 volumio-box sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:18 volumio-box sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:18 volumio-box sudo[852]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:18 volumio-box dhcpcd[849]: wlan0: soliciting an IPv6 router Feb 14 10:12:18 volumio-box ntpd[656]: Listen normally on 3 eth0 192.168.1.28:123 Feb 14 10:12:18 volumio-box ntpd[656]: new interface(s) found: waking up resolver Feb 14 10:12:19 volumio-box wireless.js[489]: trying... Feb 14 10:12:19 volumio-box sudo[855]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:19 volumio-box sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:19 volumio-box sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:19 volumio-box sudo[855]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:20 volumio-box wireless.js[489]: trying... Feb 14 10:12:20 volumio-box sudo[858]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:20 volumio-box sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:20 volumio-box sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:20 volumio-box sudo[858]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:21 volumio-box kernel: cam1-reg: disabling Feb 14 10:12:21 volumio-box kernel: cam-dummy-reg: disabling Feb 14 10:12:21 volumio-box wireless.js[489]: trying... Feb 14 10:12:21 volumio-box sudo[861]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:21 volumio-box sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:21 volumio-box sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:21 volumio-box sudo[861]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:22 volumio-box wireless.js[489]: trying... Feb 14 10:12:22 volumio-box sudo[864]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:22 volumio-box sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:22 volumio-box sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:22 volumio-box sudo[864]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:22 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:22] [info] asio async_connect error: system:111 (Connection refused) Feb 14 10:12:22 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 10:12:22 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:22] [error] handle_connect error: Underlying Transport Error Feb 14 10:12:22 volumio-box dhcpcd[849]: wlan0: using static address 192.168.1.181/24 Feb 14 10:12:22 volumio-box avahi-daemon[474]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.181. Feb 14 10:12:22 volumio-box avahi-daemon[474]: New relevant interface wlan0.IPv4 for mDNS. Feb 14 10:12:22 volumio-box avahi-daemon[474]: Registering new address record for 192.168.1.181 on wlan0.IPv4. Feb 14 10:12:22 volumio-box dhcpcd[849]: wlan0: adding route to 192.168.1.0/24 Feb 14 10:12:22 volumio-box dhcpcd[849]: wlan0: adding default route via 192.168.1.254 Feb 14 10:12:23 volumio-box wireless.js[489]: trying... Feb 14 10:12:23 volumio-box sudo[888]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:23 volumio-box sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 14 10:12:23 volumio-box sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:23 volumio-box sudo[888]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:23 volumio-box wireless.js[489]: Connected to: ----ASUS24 Feb 14 10:12:23 volumio-box wireless.js[489]: ---- Feb 14 10:12:23 volumio-box sudo[891]: pam_unix(sudo:account): account root has password changed in future Feb 14 10:12:23 volumio-box sudo[891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 10:12:23 volumio-box sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:23 volumio-box sudo[891]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:23 volumio-box wireless.js[489]: ... joined AP, wlan0 IPv4 is 192.168.1.181, ipV6 is undefined Feb 14 10:12:23 volumio-box wireless.js[489]: It's done! AP Feb 14 10:12:23 volumio-box systemd[1]: Started Wireless Services. Feb 14 10:12:23 volumio-box systemd[1]: Started Volumio Backend Module. Feb 14 10:12:23 volumio-box systemd[1]: Started Volumio Streaming Daemon. Feb 14 10:12:23 volumio-box systemd[1]: Reached target Multi-User System. Feb 14 10:12:23 volumio-box systemd[1]: Reached target Graphical Interface. Feb 14 10:12:23 volumio-box systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 14 10:12:23 volumio-box systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 14 10:12:23 volumio-box systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 14 10:12:23 volumio-box systemd[1]: Startup finished in 11.299s (kernel) + 25.416s (userspace) = 36.715s. Feb 14 10:12:23 volumio-box volumio-streaming-daemon[896]: ############################ Feb 14 10:12:23 volumio-box volumio-streaming-daemon[896]: # Volumio Streaming Daemon # Feb 14 10:12:23 volumio-box volumio-streaming-daemon[896]: # Running on port 7777 # Feb 14 10:12:23 volumio-box volumio-streaming-daemon[896]: ############################ Feb 14 10:12:23 volumio-box volumio-streaming-daemon[896]: Environment: production Feb 14 10:12:24 volumio-box ntpd[656]: Listen normally on 4 wlan0 192.168.1.181:123 Feb 14 10:12:24 volumio-box ntpd[656]: new interface(s) found: waking up resolver Feb 14 10:12:26 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:26 volumio-box volumio[895]: info: ----- Volumio3 ---- Feb 14 10:12:26 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:26 volumio-box volumio[895]: info: ----- System startup ---- Feb 14 10:12:26 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:27 volumio-box volumio[895]: info: MYVOLUMIO Environment detected Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/airplay_emulation. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/inputs. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/mpd. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/webradio. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/example_plugin. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/last_100. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin music_service/upnp_browser. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin audio_interface/outputs. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin audio_interface/upnp. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin audio_interface/alsa_controller. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin miscellanea/alarm-clock. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin miscellanea/albumart. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin miscellanea/appearance. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin miscellanea/wizard. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin miscellanea/my_music. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin user_interface/websocket. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin user_interface/mpdemulation. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin user_interface/rest_api. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/updater_comm. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/network. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/networkfs. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/services. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/system. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/i2s_dacs. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/volumiodiscovery. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it Feb 14 10:12:27 volumio-box volumio[895]: info: Plugin folders cleanup Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning into folder /volumio/app/plugins/ Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning category audio_interface Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning category miscellanea Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning category music_service Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning category plugins.json Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning category system_controller Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning category user_interface Feb 14 10:12:27 volumio-box volumio[895]: info: Scanning into folder /data/plugins/ Feb 14 10:12:27 volumio-box volumio[895]: info: Plugin folders cleanup completed Feb 14 10:12:27 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:27 volumio-box volumio[895]: info: ----- Core plugins startup ---- Feb 14 10:12:27 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:27 volumio-box volumio[895]: info: Loading plugins from folder /volumio/app/plugins/ Feb 14 10:12:27 volumio-box volumio[895]: info: Adding plugin upnp to MyMusic Plugins Feb 14 10:12:27 volumio-box volumio[895]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 14 10:12:27 volumio-box volumio[895]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 14 10:12:27 volumio-box volumio[895]: info: Loading plugins from folder /data/plugins/ Feb 14 10:12:27 volumio-box volumio[895]: info: Loading plugin "system"... Feb 14 10:12:27 volumio-box volumio[895]: info: No id defined. Creating one Feb 14 10:12:27 volumio-box volumio[895]: info: Loading plugin "appearance"... Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "network"... Feb 14 10:12:29 volumio-box volumio[895]: info: Refreshing Cached IP Addresses Feb 14 10:12:29 volumio-box sudo[920]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:29 volumio-box sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 10:12:29 volumio-box sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:29 volumio-box sudo[920]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "services"... Feb 14 10:12:29 volumio-box sudo[922]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:29 volumio-box sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "alsa_controller"... Feb 14 10:12:29 volumio-box sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:29 volumio-box sudo[925]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:29 volumio-box sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 14 10:12:29 volumio-box sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:29 volumio-box sudo[922]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:29 volumio-box volumio[895]: warn: The ALSA output card is not set, defaulting to card [object Object] Feb 14 10:12:29 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 14 10:12:29 volumio-box volumio[895]: info: Found match in Cards Database: setting mixer HDMI for card HDMI Feb 14 10:12:29 volumio-box volumio[895]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 14 10:12:29 volumio-box volumio[895]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 14 10:12:29 volumio-box volumio[895]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 14 10:12:29 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "wizard"... Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "volumio_command_line_client"... Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "upnp"... Feb 14 10:12:29 volumio-box volumio[895]: info: [1550139149626] Starting Upmpd Daemon Feb 14 10:12:29 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "my_music"... Feb 14 10:12:29 volumio-box volumio[895]: info: Loading plugin "mpd"... Feb 14 10:12:30 volumio-box volumio[895]: info: Creating MPD Configuration file Feb 14 10:12:30 volumio-box sudo[925]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:30 volumio-box volumio[895]: info: Loading plugin "upnp_browser"... Feb 14 10:12:30 volumio-box sudo[941]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:30 volumio-box sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 14 10:12:30 volumio-box sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:30 volumio-box sudo[941]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:30 volumio-box sudo[943]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:30 volumio-box sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 14 10:12:30 volumio-box sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:30 volumio-box systemd[1]: Starting Music Player Daemon... Feb 14 10:12:30 volumio-box volumio[895]: info: Loading plugin "networkfs"... Feb 14 10:12:31 volumio-box volumio[895]: info: Starting Udev Watcher for removable devices Feb 14 10:12:31 volumio-box sudo[951]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:31 volumio-box sudo[951]: 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.241/Multimedia/music/BOG/ /mnt/NAS/Multimedia Feb 14 10:12:31 volumio-box sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:31 volumio-box sudo[953]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:31 volumio-box sudo[953]: 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.242/Multimedia\music\new\ /mnt/NAS/New Feb 14 10:12:31 volumio-box sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:31 volumio-box kernel: FS-Cache: Netfs 'cifs' registered for caching Feb 14 10:12:31 volumio-box kernel: Key type cifs.spnego registered Feb 14 10:12:31 volumio-box kernel: Key type cifs.idmap registered Feb 14 10:12:31 volumio-box kernel: CIFS: Attempting to mount //192.168.1.241/Multimedia/music/BOG/ Feb 14 10:12:31 volumio-box 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. Feb 14 10:12:31 volumio-box kernel: CIFS: Attempting to mount //192.168.1.242/Multimedia\music\new\ Feb 14 10:12:31 volumio-box volumio[895]: info: Ignoring mount for partition: boot Feb 14 10:12:31 volumio-box volumio[895]: info: Ignoring mount for partition: volumio Feb 14 10:12:31 volumio-box volumio[895]: info: Ignoring mount for partition: volumio_data Feb 14 10:12:31 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 10:12:31 volumio-box volumio[895]: info: Loading plugin "alarm-clock"... Feb 14 10:12:31 volumio-box sudo[951]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:31 volumio-box sudo[953]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:31 volumio-box mpd[948]: exception: Error in /etc/mpd.conf line 1 Feb 14 10:12:31 volumio-box mpd[948]: exception: nested: Letter expected Feb 14 10:12:31 volumio-box systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 14 10:12:31 volumio-box systemd[1]: mpd.service: Failed with result 'exit-code'. Feb 14 10:12:31 volumio-box systemd[1]: Failed to start Music Player Daemon. Feb 14 10:12:31 volumio-box sudo[943]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:31 volumio-box volumio[895]: info: Loading plugin "airplay_emulation"... Feb 14 10:12:31 volumio-box volumio[895]: info: Starting Shairport Sync Feb 14 10:12:31 volumio-box volumio[895]: info: Loading plugin "last_100"... Feb 14 10:12:31 volumio-box volumio[895]: info: Loading plugin "webradio"... Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "i2s_dacs"... Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "volumiodiscovery"... Feb 14 10:12:32 volumio-box volumio[895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 10:12:32 volumio-box volumio[895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 10:12:32 volumio-box volumio[895]: *** WARNING *** For more information see Feb 14 10:12:32 volumio-box volumio[895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 10:12:32 volumio-box node[895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 10:12:32 volumio-box volumio[895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 10:12:32 volumio-box volumio[895]: *** WARNING *** For more information see Feb 14 10:12:32 volumio-box node[895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 10:12:32 volumio-box node[895]: *** WARNING *** For more information see Feb 14 10:12:32 volumio-box node[895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 10:12:32 volumio-box node[895]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 10:12:32 volumio-box node[895]: *** WARNING *** For more information see Feb 14 10:12:32 volumio-box volumio[895]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 14 10:12:32 volumio-box volumio[895]: info: Discovery: Started advertising with name: undefined Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "outputs"... Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "albumart"... Feb 14 10:12:32 volumio-box volumio[895]: info: Plugin example_plugin is not enabled Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "inputs"... Feb 14 10:12:32 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:32] [connect] Successful connection Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "updater_comm"... Feb 14 10:12:32 volumio-box volumio[895]: info: Plugin mpdemulation is not enabled Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "rest_api"... Feb 14 10:12:32 volumio-box volumio[895]: info: Loading plugin "websocket"... Feb 14 10:12:32 volumio-box volumio[895]: info: Loading i18n strings for locale nl Feb 14 10:12:32 volumio-box volumio[895]: Updating browse sources language Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::initPlayerControls Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 10:12:32 volumio-box volumio[895]: Express server listening on port 3000 Feb 14 10:12:32 volumio-box volumio[895]: [Metrics] WebUI: 7s 390.67ms Feb 14 10:12:32 volumio-box volumio[895]: Forking 3 albumart workers Feb 14 10:12:32 volumio-box volumio[895]: info: CoreStateMachine::resetVolumioState Feb 14 10:12:32 volumio-box volumio[895]: info: CoreStateMachine::getcurrentVolume Feb 14 10:12:32 volumio-box volumio[895]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 10:12:32 volumio-box volumio[895]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 14 10:12:32 volumio-box volumio[895]: Job for mpd.service failed because the control process exited with error code. Feb 14 10:12:32 volumio-box volumio[895]: See "systemctl status mpd.service" and "journalctl -xe" for details. Feb 14 10:12:32 volumio-box volumio[895]: info: MPD Permissions set Feb 14 10:12:32 volumio-box systemd[1]: Starting Music Player Daemon... Feb 14 10:12:32 volumio-box volumio-remote-updater[488]: [2019-02-14 10:12:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1550139152 101 Feb 14 10:12:32 volumio-box volumio[895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 10:12:33 volumio-box volumio[895]: info: Setting Device type: Raspberry PI Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::setRepeat false single undefined Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::pushState Feb 14 10:12:33 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::volumioPushState Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::setRandom true Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::pushState Feb 14 10:12:33 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::volumioPushState Feb 14 10:12:33 volumio-box volumio[895]: info: Completed loading Core Plugins Feb 14 10:12:33 volumio-box volumio[895]: info: Preparing to generate the ALSA configuration file Feb 14 10:12:33 volumio-box volumio[895]: info: VolumeController:: Volume=96 Mute =false Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::pushState Feb 14 10:12:33 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::volumioPushState Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::updateTrackBlock Feb 14 10:12:33 volumio-box volumio[895]: info: CorePlayQueue::getTrackBlock Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 14 10:12:33 volumio-box volumio[895]: info: CoreStateMachine::getcurrentVolume Feb 14 10:12:33 volumio-box volumio[895]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 10:12:33 volumio-box volumio[895]: info: Reloading queue from file Feb 14 10:12:33 volumio-box volumio[895]: Starting albumart workers Feb 14 10:12:33 volumio-box volumio[895]: info: Discovery: adding 878d1512-649a-493b-807d-7033beeb509d Feb 14 10:12:33 volumio-box volumio[895]: info: Discovery: Found device Volumio orange pi Feb 14 10:12:34 volumio-box volumio[895]: info: Discovery: Connecting to remote: 192.168.1.228 Feb 14 10:12:34 volumio-box volumio[895]: info: VolumeController:: Volume=96 Mute =false Feb 14 10:12:34 volumio-box volumio[895]: info: CoreStateMachine::pushState Feb 14 10:12:34 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 10:12:34 volumio-box volumio[895]: Starting albumart workers Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::volumioPushState Feb 14 10:12:34 volumio-box volumio[895]: info: CoreStateMachine::updateTrackBlock Feb 14 10:12:34 volumio-box volumio[895]: info: CorePlayQueue::getTrackBlock Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 10:12:34 volumio-box volumio[895]: info: VolumeController:: Volume=96 Mute =false Feb 14 10:12:34 volumio-box volumio[895]: info: CoreStateMachine::pushState Feb 14 10:12:34 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::volumioPushState Feb 14 10:12:34 volumio-box volumio[895]: info: Asound.conf file written Feb 14 10:12:34 volumio-box sudo[1046]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:34 volumio-box sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 14 10:12:34 volumio-box sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:34 volumio-box sudo[1046]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:34 volumio-box volumio[895]: info: Output device has changed, restarting MPD Feb 14 10:12:34 volumio-box volumio[895]: info: Output device has changed, restarting Shairport Sync Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 10:12:34 volumio-box volumio[895]: Starting albumart workers Feb 14 10:12:34 volumio-box sudo[1052]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:34 volumio-box sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 14 10:12:34 volumio-box sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:34 volumio-box sudo[1054]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:34 volumio-box sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 14 10:12:34 volumio-box sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:34 volumio-box sudo[1052]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:34 volumio-box volumio[895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 10:12:34 volumio-box volumio[895]: info: ___________ START PLUGINS ___________ Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 10:12:34 volumio-box volumio[895]: info: [1550139154436] CoreMusicLibrary::Adding element Media Servers Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 10:12:34 volumio-box volumio[895]: Cannot find translation for sourceMedia Servers Feb 14 10:12:34 volumio-box systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 14 10:12:34 volumio-box systemd[1]: mpd.service: Succeeded. Feb 14 10:12:34 volumio-box systemd[1]: Stopped Music Player Daemon. Feb 14 10:12:34 volumio-box systemd[1]: Starting Music Player Daemon... Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 10:12:34 volumio-box volumio[895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 10:12:34 volumio-box systemd[1]: systemd-fsckd.service: Succeeded. Feb 14 10:12:34 volumio-box volumio[895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 10:12:34 volumio-box volumio[895]: info: [1550139154648] CoreMusicLibrary::Adding element Last_100 Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 10:12:34 volumio-box volumio[895]: Cannot find translation for sourceMedia Servers Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 10:12:34 volumio-box volumio[895]: info: [1550139154655] CoreMusicLibrary::Adding element Webradio Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 10:12:34 volumio-box volumio[895]: Cannot find translation for sourceMedia Servers Feb 14 10:12:34 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 10:12:34 volumio-box volumio[895]: info: Volumio Calling Home Feb 14 10:12:34 volumio-box volumio[895]: info: Completed starting Core Plugins Feb 14 10:12:34 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:34 volumio-box volumio[895]: info: ----- MyVolumio plugins startup ---- Feb 14 10:12:34 volumio-box volumio[895]: info: ------------------------------------------- Feb 14 10:12:34 volumio-box volumio[895]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 14 10:12:35 volumio-box volumio[895]: info: MPD Permissions set Feb 14 10:12:35 volumio-box volumio[895]: info: Discovery: this is already registered, 878d1512-649a-493b-807d-7033beeb509d Feb 14 10:12:35 volumio-box volumio[895]: info: Discovery: Found device Volumio orange pi Feb 14 10:12:35 volumio-box volumio[895]: info: Discovery: Connecting to remote: 192.168.1.228 Feb 14 10:12:35 volumio-box volumio[895]: info: Volumio called home Feb 14 10:12:35 volumio-box volumio[895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Feb 14 10:12:35 volumio-box volumio[895]: info: VolumeController:: Volume=96 Mute =false Feb 14 10:12:35 volumio-box volumio[895]: info: CoreStateMachine::pushState Feb 14 10:12:35 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::volumioPushState Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 10:12:35 volumio-box volumio[895]: info: Starting Shairport Sync Feb 14 10:12:35 volumio-box volumio[895]: info: Starting Shairport Sync Feb 14 10:12:35 volumio-box volumio[895]: info: Starting Shairport Sync Feb 14 10:12:35 volumio-box sudo[1101]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:35 volumio-box sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 14 10:12:35 volumio-box sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:35 volumio-box sudo[1103]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:35 volumio-box sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 14 10:12:35 volumio-box sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:35 volumio-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 14 10:12:35 volumio-box systemd[1]: shairport-sync.service: Succeeded. Feb 14 10:12:35 volumio-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:35 volumio-box volumio[895]: info: Discovery: Connected to remote: 192.168.1.228 Feb 14 10:12:35 volumio-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:35 volumio-box sudo[1101]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:35 volumio-box sudo[1107]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:35 volumio-box sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 14 10:12:35 volumio-box sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 10:12:35 volumio-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 14 10:12:35 volumio-box systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 14 10:12:35 volumio-box systemd[1]: shairport-sync.service: Succeeded. Feb 14 10:12:35 volumio-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:35 volumio-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:35 volumio-box sudo[1103]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:35 volumio-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 14 10:12:35 volumio-box systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 14 10:12:35 volumio-box systemd[1]: shairport-sync.service: Succeeded. Feb 14 10:12:35 volumio-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:35 volumio-box volumio[895]: verbose: New Socket.io Connection to 192.168.1.181 from 192.168.1.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 Feb 14 10:12:35 volumio-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 10:12:35 volumio-box sudo[1107]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:35 volumio-box volumio[895]: info: Shairport-Sync Started Feb 14 10:12:35 volumio-box volumio[895]: Error adding Membership: Error: addMembership EINVAL Feb 14 10:12:35 volumio-box volumio[895]: info: Shairport-Sync Started Feb 14 10:12:35 volumio-box volumio[895]: info: Shairport-Sync Started Feb 14 10:12:35 volumio-box volumio[895]: info: CoreCommandRouter::volumioGetState Feb 14 10:12:35 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:36 volumio-box volumio[895]: info: CoreCommandRouter::volumioGetState Feb 14 10:12:36 volumio-box volumio[895]: info: CorePlayQueue::getTrack 0 Feb 14 10:12:36 volumio-box volumio[895]: info: CoreCommandRouter::volumioGetQueue Feb 14 10:12:36 volumio-box volumio[895]: info: CoreStateMachine::getQueue Feb 14 10:12:36 volumio-box volumio[895]: info: CorePlayQueue::getQueue Feb 14 10:12:36 volumio-box volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Feb 14 10:12:36 volumio-box mpd[1070]: Feb 14 10:12 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 14 10:12:36 volumio-box mpd[1070]: Feb 14 10:12 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 14 10:12:36 volumio-box mpd[1070]: Feb 14 10:12 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 14 10:12:36 volumio-box systemd[1]: Started Music Player Daemon. Feb 14 10:12:36 volumio-box sudo[1054]: pam_unix(sudo:session): session closed for user root Feb 14 10:12:37 volumio-box volumio[895]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Feb 14 10:12:37 volumio-box volumio[895]: info: Discovery: Started advertising with name: undefined Feb 14 10:12:37 volumio-box volumio[895]: error: updateQueue error: null Feb 14 10:12:37 volumio-box volumio[895]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 14 10:12:37 volumio-box volumio[895]: error: updateQueue error: null Feb 14 10:12:37 volumio-box volumio[895]: Cannot compose Albumart path Feb 14 10:12:39 volumio-box volumio[895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 10:12:39 volumio-box volumio[895]: TypeError: Cannot read property 'charAt' of undefined Feb 14 10:12:39 volumio-box volumio[895]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Feb 14 10:12:39 volumio-box volumio[895]: at listOnTimeout (internal/timers.js:554:17) Feb 14 10:12:39 volumio-box volumio[895]: at processTimers (internal/timers.js:497:7) Feb 14 10:12:39 volumio-box volumio[895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 10:12:40 volumio-box sudo[1125]: pam_unix(sudo:account): account volumio has password changed in future Feb 14 10:12:40 volumio-box sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2019-02-14 10:11 Feb 14 10:12:40 volumio-box sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"