-- Logs begin at Tue 2022-08-16 23:46:08 UTC, end at Tue 2022-08-16 23:47:04 UTC. -- Aug 16 23:46:08 lobbymusic systemd-journal[184]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.7M free of 480.6M available → current limit 30.0M). Aug 16 23:46:08 lobbymusic kernel: Booting Linux on physical CPU 0x0 Aug 16 23:46:08 lobbymusic kernel: Linux version 4.19.118-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1311 SMP Mon Apr 27 14:21:24 BST 2020 Aug 16 23:46:08 lobbymusic kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Aug 16 23:46:08 lobbymusic kernel: CPU: div instructions available: patching division code Aug 16 23:46:08 lobbymusic kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Aug 16 23:46:08 lobbymusic kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Aug 16 23:46:08 lobbymusic kernel: Memory policy: Data cache writealloc Aug 16 23:46:08 lobbymusic kernel: cma: Reserved 8 MiB at 0x3d800000 Aug 16 23:46:08 lobbymusic kernel: On node 0 totalpages: 253952 Aug 16 23:46:08 lobbymusic kernel: Normal zone: 2232 pages used for memmap Aug 16 23:46:08 lobbymusic kernel: Normal zone: 0 pages reserved Aug 16 23:46:08 lobbymusic kernel: Normal zone: 253952 pages, LIFO batch:63 Aug 16 23:46:08 lobbymusic kernel: random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0 Aug 16 23:46:08 lobbymusic kernel: percpu: Embedded 16 pages/cpu s36864 r8192 d20480 u65536 Aug 16 23:46:08 lobbymusic kernel: pcpu-alloc: s36864 r8192 d20480 u65536 alloc=16*4096 Aug 16 23:46:08 lobbymusic kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 16 23:46:08 lobbymusic kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Aug 16 23:46:08 lobbymusic kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet 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 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 snd-bcm2835.enable_compat_alsa=1 snd_bcm2835.enable_headphones=1 Aug 16 23:46:08 lobbymusic kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Aug 16 23:46:08 lobbymusic kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Aug 16 23:46:08 lobbymusic kernel: Memory: 964208K/1015808K available (8192K kernel code, 656K rwdata, 2232K rodata, 1024K init, 831K bss, 43408K reserved, 8192K cma-reserved) Aug 16 23:46:08 lobbymusic kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe000000 ( 992 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x(ptrval) - 0x(ptrval) (9184 kB) .init : 0x(ptrval) - 0x(ptrval) (1024 kB) .data : 0x(ptrval) - 0x(ptrval) ( 657 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 832 kB) Aug 16 23:46:08 lobbymusic kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 16 23:46:08 lobbymusic kernel: ftrace: allocating 27093 entries in 80 pages Aug 16 23:46:08 lobbymusic kernel: rcu: Hierarchical RCU implementation. Aug 16 23:46:08 lobbymusic kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Aug 16 23:46:08 lobbymusic kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Aug 16 23:46:08 lobbymusic kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Aug 16 23:46:08 lobbymusic kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Aug 16 23:46:08 lobbymusic kernel: Switching to timer-based delay loop, resolution 52ns Aug 16 23:46:08 lobbymusic kernel: Console: colour dummy device 80x30 Aug 16 23:46:08 lobbymusic kernel: console [tty1] enabled Aug 16 23:46:08 lobbymusic kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Aug 16 23:46:08 lobbymusic kernel: pid_max: default: 32768 minimum: 301 Aug 16 23:46:08 lobbymusic kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Aug 16 23:46:08 lobbymusic kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Aug 16 23:46:08 lobbymusic kernel: CPU: Testing write buffer coherency: ok Aug 16 23:46:08 lobbymusic kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Aug 16 23:46:08 lobbymusic kernel: Setting up static identity map for 0x100000 - 0x10003c Aug 16 23:46:08 lobbymusic kernel: rcu: Hierarchical SRCU implementation. Aug 16 23:46:08 lobbymusic kernel: smp: Bringing up secondary CPUs ... Aug 16 23:46:08 lobbymusic kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Aug 16 23:46:08 lobbymusic kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Aug 16 23:46:08 lobbymusic kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Aug 16 23:46:08 lobbymusic kernel: smp: Brought up 1 node, 4 CPUs Aug 16 23:46:08 lobbymusic kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Aug 16 23:46:08 lobbymusic kernel: CPU: All CPU(s) started in HYP mode. Aug 16 23:46:08 lobbymusic kernel: CPU: Virtualization extensions available. Aug 16 23:46:08 lobbymusic kernel: devtmpfs: initialized Aug 16 23:46:08 lobbymusic kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Aug 16 23:46:08 lobbymusic kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Aug 16 23:46:08 lobbymusic kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Aug 16 23:46:08 lobbymusic kernel: pinctrl core: initialized pinctrl subsystem Aug 16 23:46:08 lobbymusic kernel: NET: Registered protocol family 16 Aug 16 23:46:08 lobbymusic kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Aug 16 23:46:08 lobbymusic kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Aug 16 23:46:08 lobbymusic kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Aug 16 23:46:08 lobbymusic kernel: Serial: AMBA PL011 UART driver Aug 16 23:46:08 lobbymusic kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Aug 16 23:46:08 lobbymusic kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2020-04-27 14:35, variant start Aug 16 23:46:08 lobbymusic kernel: raspberrypi-firmware soc:firmware: Firmware hash is 3a8f1793b758d6fb7f375edaa260e069ecd34c88 Aug 16 23:46:08 lobbymusic kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 Aug 16 23:46:08 lobbymusic kernel: SCSI subsystem initialized Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new interface driver usbfs Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new interface driver hub Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new device driver usb Aug 16 23:46:08 lobbymusic kernel: clocksource: Switched to clocksource arch_sys_counter Aug 16 23:46:08 lobbymusic kernel: VFS: Disk quotas dquot_6.6.0 Aug 16 23:46:08 lobbymusic kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Aug 16 23:46:08 lobbymusic kernel: FS-Cache: Loaded Aug 16 23:46:08 lobbymusic kernel: CacheFiles: Loaded Aug 16 23:46:08 lobbymusic kernel: NET: Registered protocol family 2 Aug 16 23:46:08 lobbymusic kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) Aug 16 23:46:08 lobbymusic kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Aug 16 23:46:08 lobbymusic kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Aug 16 23:46:08 lobbymusic kernel: TCP: Hash tables configured (established 8192 bind 8192) Aug 16 23:46:08 lobbymusic kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Aug 16 23:46:08 lobbymusic kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Aug 16 23:46:08 lobbymusic kernel: NET: Registered protocol family 1 Aug 16 23:46:08 lobbymusic kernel: RPC: Registered named UNIX socket transport module. Aug 16 23:46:08 lobbymusic kernel: RPC: Registered udp transport module. Aug 16 23:46:08 lobbymusic kernel: RPC: Registered tcp transport module. Aug 16 23:46:08 lobbymusic kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 16 23:46:08 lobbymusic kernel: Trying to unpack rootfs image as initramfs... Aug 16 23:46:08 lobbymusic kernel: Freeing initrd memory: 19516K Aug 16 23:46:08 lobbymusic kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Aug 16 23:46:08 lobbymusic kernel: Initialise system trusted keyrings Aug 16 23:46:08 lobbymusic kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Aug 16 23:46:08 lobbymusic kernel: FS-Cache: Netfs 'nfs' registered for caching Aug 16 23:46:08 lobbymusic kernel: NFS: Registering the id_resolver key type Aug 16 23:46:08 lobbymusic kernel: Key type id_resolver registered Aug 16 23:46:08 lobbymusic kernel: Key type id_legacy registered Aug 16 23:46:08 lobbymusic kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Aug 16 23:46:08 lobbymusic kernel: Key type asymmetric registered Aug 16 23:46:08 lobbymusic kernel: Asymmetric key parser 'x509' registered Aug 16 23:46:08 lobbymusic kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 16 23:46:08 lobbymusic kernel: io scheduler noop registered (default) Aug 16 23:46:08 lobbymusic kernel: io scheduler deadline registered Aug 16 23:46:08 lobbymusic kernel: io scheduler cfq registered Aug 16 23:46:08 lobbymusic kernel: io scheduler mq-deadline registered Aug 16 23:46:08 lobbymusic kernel: io scheduler kyber registered Aug 16 23:46:08 lobbymusic kernel: bcm2708_fb soc:fb: FB found 1 display(s) Aug 16 23:46:08 lobbymusic kernel: Console: switching to colour frame buffer device 80x30 Aug 16 23:46:08 lobbymusic kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Aug 16 23:46:08 lobbymusic kernel: bcm2835-rng 3f104000.rng: hwrng registered Aug 16 23:46:08 lobbymusic kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Aug 16 23:46:08 lobbymusic kernel: vc-sm: Videocore shared memory driver Aug 16 23:46:08 lobbymusic kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Aug 16 23:46:08 lobbymusic kernel: brd: module loaded Aug 16 23:46:08 lobbymusic kernel: loop: module loaded Aug 16 23:46:08 lobbymusic kernel: Loading iSCSI transport class v2.0-870. Aug 16 23:46:08 lobbymusic kernel: libphy: Fixed MDIO Bus: probed Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new interface driver lan78xx Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new interface driver smsc95xx Aug 16 23:46:08 lobbymusic kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Aug 16 23:46:08 lobbymusic kernel: dwc_otg 3f980000.usb: base=(ptrval) Aug 16 23:46:08 lobbymusic kernel: Core Release: 2.80a Aug 16 23:46:08 lobbymusic kernel: Setting default values for core params Aug 16 23:46:08 lobbymusic kernel: Finished setting default values for core params Aug 16 23:46:08 lobbymusic kernel: Using Buffer DMA mode Aug 16 23:46:08 lobbymusic kernel: Periodic Transfer Interrupt Enhancement - disabled Aug 16 23:46:08 lobbymusic kernel: Multiprocessor Interrupt Enhancement - disabled Aug 16 23:46:08 lobbymusic kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Aug 16 23:46:08 lobbymusic kernel: Dedicated Tx FIFOs mode Aug 16 23:46:08 lobbymusic kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = bd904000 dma = 0xfd904000 len=9024 Aug 16 23:46:08 lobbymusic kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Aug 16 23:46:08 lobbymusic kernel: dwc_otg: Microframe scheduler enabled Aug 16 23:46:08 lobbymusic kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Aug 16 23:46:08 lobbymusic kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8068fda0 length 36 Aug 16 23:46:08 lobbymusic kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Aug 16 23:46:08 lobbymusic kernel: dwc_otg 3f980000.usb: DWC OTG Controller Aug 16 23:46:08 lobbymusic kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Aug 16 23:46:08 lobbymusic kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Aug 16 23:46:08 lobbymusic kernel: Init: Port Power? op_state=1 Aug 16 23:46:08 lobbymusic kernel: Init: Power Port (0) Aug 16 23:46:08 lobbymusic kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 Aug 16 23:46:08 lobbymusic kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 16 23:46:08 lobbymusic kernel: usb usb1: Product: DWC OTG Controller Aug 16 23:46:08 lobbymusic kernel: usb usb1: Manufacturer: Linux 4.19.118-v7+ dwc_otg_hcd Aug 16 23:46:08 lobbymusic kernel: usb usb1: SerialNumber: 3f980000.usb Aug 16 23:46:08 lobbymusic kernel: hub 1-0:1.0: USB hub found Aug 16 23:46:08 lobbymusic kernel: hub 1-0:1.0: 1 port detected Aug 16 23:46:08 lobbymusic kernel: dwc_otg: FIQ enabled Aug 16 23:46:08 lobbymusic kernel: dwc_otg: NAK holdoff enabled Aug 16 23:46:08 lobbymusic kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 16 23:46:08 lobbymusic kernel: Module dwc_common_port init Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new interface driver usb-storage Aug 16 23:46:08 lobbymusic kernel: mousedev: PS/2 mouse device common for all mice Aug 16 23:46:08 lobbymusic kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 16 23:46:08 lobbymusic kernel: bcm2835-cpufreq: min=600000 max=1200000 Aug 16 23:46:08 lobbymusic kernel: sdhci: Secure Digital Host Controller Interface driver Aug 16 23:46:08 lobbymusic kernel: sdhci: Copyright(c) Pierre Ossman Aug 16 23:46:08 lobbymusic kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Aug 16 23:46:08 lobbymusic kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Aug 16 23:46:08 lobbymusic kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 16 23:46:08 lobbymusic kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 16 23:46:08 lobbymusic kernel: hidraw: raw HID events driver (C) Jiri Kosina Aug 16 23:46:08 lobbymusic kernel: usbcore: registered new interface driver usbhid Aug 16 23:46:08 lobbymusic kernel: usbhid: USB HID core driver Aug 16 23:46:08 lobbymusic kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 Aug 16 23:46:08 lobbymusic kernel: [vc_sm_connected_init]: start Aug 16 23:46:08 lobbymusic kernel: [vc_sm_connected_init]: end - returning 0 Aug 16 23:46:08 lobbymusic kernel: Initializing XFRM netlink socket Aug 16 23:46:08 lobbymusic kernel: NET: Registered protocol family 17 Aug 16 23:46:08 lobbymusic kernel: Key type dns_resolver registered Aug 16 23:46:08 lobbymusic kernel: Registering SWP/SWPB emulation handler Aug 16 23:46:08 lobbymusic kernel: registered taskstats version 1 Aug 16 23:46:08 lobbymusic kernel: Loading compiled-in X.509 certificates Aug 16 23:46:08 lobbymusic kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Aug 16 23:46:08 lobbymusic kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Aug 16 23:46:08 lobbymusic kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Aug 16 23:46:08 lobbymusic kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Aug 16 23:46:08 lobbymusic kernel: sdhost: log_buf @ (ptrval) (fd907000) Aug 16 23:46:08 lobbymusic kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Aug 16 23:46:08 lobbymusic kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 16 23:46:08 lobbymusic kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 16 23:46:08 lobbymusic kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Aug 16 23:46:08 lobbymusic kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Aug 16 23:46:08 lobbymusic kernel: of_cfs_init Aug 16 23:46:08 lobbymusic kernel: of_cfs_init: OK Aug 16 23:46:08 lobbymusic kernel: Freeing unused kernel memory: 1024K Aug 16 23:46:08 lobbymusic kernel: Run /init as init process Aug 16 23:46:08 lobbymusic kernel: random: fast init done Aug 16 23:46:08 lobbymusic kernel: mmc0: host does not support reading read-only switch, assuming write-enable Aug 16 23:46:08 lobbymusic kernel: mmc0: new high speed SDHC card at address aaaa Aug 16 23:46:08 lobbymusic kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Aug 16 23:46:08 lobbymusic kernel: mmcblk0: p1 p2 p3 Aug 16 23:46:08 lobbymusic kernel: mmc1: new high speed SDIO card at address 0001 Aug 16 23:46:08 lobbymusic kernel: Indeed it is in host mode hprt0 = 00021501 Aug 16 23:46:08 lobbymusic initramfs: Booting Volumio for BCM2835 Aug 16 23:46:08 lobbymusic initramfs: This script mounts rootfs RO with an overlay RW layer. Aug 16 23:46:08 lobbymusic kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 16 23:46:08 lobbymusic kernel: fuse init (API version 7.27) Aug 16 23:46:08 lobbymusic initramfs: IMGPART=/dev/mmcblk0p2 Aug 16 23:46:08 lobbymusic initramfs: IMGFILE=/volumio_current.sqsh Aug 16 23:46:08 lobbymusic initramfs: Boot delay (except first time) will be 5 seconds Aug 16 23:46:08 lobbymusic initramfs: /dev/mmcblk0p2: Aug 16 23:46:08 lobbymusic kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 16 23:46:08 lobbymusic kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Aug 16 23:46:08 lobbymusic initramfs: Doing a 5 second delay here to give kernel load a headstart Aug 16 23:46:08 lobbymusic kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Aug 16 23:46:08 lobbymusic kernel: Indeed it is in host mode hprt0 = 00001101 Aug 16 23:46:08 lobbymusic kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Aug 16 23:46:08 lobbymusic kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 16 23:46:08 lobbymusic kernel: hub 1-1:1.0: USB hub found Aug 16 23:46:08 lobbymusic kernel: hub 1-1:1.0: 5 ports detected Aug 16 23:46:08 lobbymusic kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Aug 16 23:46:08 lobbymusic kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Aug 16 23:46:08 lobbymusic kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 16 23:46:08 lobbymusic kernel: smsc95xx v1.0.6 Aug 16 23:46:08 lobbymusic kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:51:e5:16 Aug 16 23:46:08 lobbymusic initramfs: Checking for USB updates Aug 16 23:46:08 lobbymusic initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Aug 16 23:46:08 lobbymusic kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 16 23:46:08 lobbymusic kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 16 23:46:08 lobbymusic kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 16 23:46:08 lobbymusic kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Aug 16 23:46:08 lobbymusic initramfs: With Option: Aug 16 23:46:08 lobbymusic initramfs: VOLUMIO_VERSION="2.907" Aug 16 23:46:08 lobbymusic initramfs: Finish initramfs, continue booting Volumio Aug 16 23:46:08 lobbymusic kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 16 23:46:08 lobbymusic systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Aug 16 23:46:08 lobbymusic systemd[1]: Detected architecture 'arm'. Aug 16 23:46:08 lobbymusic kernel: NET: Registered protocol family 10 Aug 16 23:46:08 lobbymusic kernel: Segment Routing with IPv6 Aug 16 23:46:08 lobbymusic systemd[1]: Inserted module 'ipv6' Aug 16 23:46:08 lobbymusic systemd[1]: Set hostname to . Aug 16 23:46:08 lobbymusic kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Aug 16 23:46:08 lobbymusic kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 16 23:46:08 lobbymusic systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Aug 16 23:46:08 lobbymusic systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Aug 16 23:46:08 lobbymusic systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Aug 16 23:46:08 lobbymusic systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Aug 16 23:46:08 lobbymusic systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Aug 16 23:46:08 lobbymusic systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Aug 16 23:46:08 lobbymusic kernel: i2c /dev entries driver Aug 16 23:46:08 lobbymusic systemd-journal[184]: Journal started Aug 16 23:46:08 lobbymusic systemd[1]: Starting Slices. Aug 16 23:46:08 lobbymusic systemd[1]: Reached target Slices. Aug 16 23:46:08 lobbymusic systemd[1]: Starting Remount Root and Kernel File Systems... Aug 16 23:46:08 lobbymusic systemd[1]: Started Restore / save the current clock. Aug 16 23:46:08 lobbymusic systemd[1]: Started Create list of required static device nodes for the current kernel. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted POSIX Message Queue File System. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted Debug File System. Aug 16 23:46:08 lobbymusic systemd[1]: Time has been changed Aug 16 23:46:08 lobbymusic systemd[1]: Starting Create Static Device Nodes in /dev... Aug 16 23:46:08 lobbymusic systemd[1]: Started Remount Root and Kernel File Systems. Aug 16 23:46:08 lobbymusic systemd[1]: Started Various fixups to make systemd work better on Debian. Aug 16 23:46:08 lobbymusic systemd-modules-load[182]: Inserted module 'i2c_dev' Aug 16 23:46:08 lobbymusic systemd[1]: Started Load Kernel Modules. Aug 16 23:46:08 lobbymusic fake-hwclock[171]: Tue Aug 16 23:46:08 UTC 2022 Aug 16 23:46:08 lobbymusic systemd[1]: Mounting FUSE Control File System... Aug 16 23:46:08 lobbymusic systemd[1]: Mounting Configuration File System... Aug 16 23:46:08 lobbymusic systemd[1]: Starting Apply Kernel Variables... Aug 16 23:46:08 lobbymusic systemd[1]: Starting udev Kernel Device Manager... Aug 16 23:46:08 lobbymusic systemd[1]: Starting Local File Systems (Pre). Aug 16 23:46:08 lobbymusic systemd[1]: Reached target Local File Systems (Pre). Aug 16 23:46:08 lobbymusic systemd[1]: Mounting /tmp... Aug 16 23:46:08 lobbymusic systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 16 23:46:08 lobbymusic systemd[1]: Mounting /var/spool/cups... Aug 16 23:46:08 lobbymusic systemd[1]: Mounting /var/log... Aug 16 23:46:08 lobbymusic systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted Configuration File System. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted FUSE Control File System. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted /tmp. Aug 16 23:46:08 lobbymusic systemd[1]: Started Apply Kernel Variables. Aug 16 23:46:08 lobbymusic systemd-udevd[212]: starting version 215 Aug 16 23:46:08 lobbymusic systemd[1]: Mounted /var/log. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted /var/spool/cups. Aug 16 23:46:08 lobbymusic systemd[1]: Started udev Kernel Device Manager. Aug 16 23:46:08 lobbymusic systemd[1]: Starting Show Plymouth Boot Screen... Aug 16 23:46:08 lobbymusic systemd[1]: Starting Copy rules generated while the root was ro... Aug 16 23:46:08 lobbymusic systemd[1]: Mounting /var/spool/cups/tmp... Aug 16 23:46:08 lobbymusic systemd[1]: Started Copy rules generated while the root was ro. Aug 16 23:46:08 lobbymusic systemd[1]: Mounted /var/spool/cups/tmp. Aug 16 23:46:08 lobbymusic systemd[1]: Started Show Plymouth Boot Screen. Aug 16 23:46:08 lobbymusic systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Aug 16 23:46:08 lobbymusic systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 16 23:46:08 lobbymusic systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Aug 16 23:46:08 lobbymusic systemd[1]: Starting Paths. Aug 16 23:46:08 lobbymusic systemd[1]: Reached target Paths. Aug 16 23:46:08 lobbymusic kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 16 23:46:08 lobbymusic kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 16 23:46:08 lobbymusic kernel: [vc_sm_connected_init]: start Aug 16 23:46:08 lobbymusic kernel: [vc_sm_connected_init]: installed successfully Aug 16 23:46:08 lobbymusic kernel: media: Linux media interface: v0.10 Aug 16 23:46:08 lobbymusic kernel: videodev: Linux video capture interface: v2.00 Aug 16 23:46:08 lobbymusic kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 16 23:46:09 lobbymusic kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 16 23:46:09 lobbymusic kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 16 23:46:09 lobbymusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 16 23:46:09 lobbymusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 16 23:46:09 lobbymusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 16 23:46:09 lobbymusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 16 23:46:09 lobbymusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 16 23:46:09 lobbymusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 16 23:46:09 lobbymusic kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Aug 16 23:46:09 lobbymusic kernel: snd_bcm2835: unknown parameter 'index' ignored Aug 16 23:46:09 lobbymusic kernel: bcm2835_audio soc:audio: card created with 5 channels Aug 16 23:46:09 lobbymusic kernel: bcm2835_audio soc:audio: card created with 3 channels Aug 16 23:46:09 lobbymusic systemd[1]: Starting Sound Card. Aug 16 23:46:09 lobbymusic systemd[1]: Reached target Sound Card. Aug 16 23:46:09 lobbymusic kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 16 23:46:09 lobbymusic kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 16 23:46:09 lobbymusic kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Aug 16 23:46:09 lobbymusic kernel: cfg80211: failed to load regulatory.db Aug 16 23:46:09 lobbymusic systemd[1]: Found device /dev/mmcblk0p1. Aug 16 23:46:09 lobbymusic systemd[1]: Starting File System Check on /dev/mmcblk0p1... Aug 16 23:46:09 lobbymusic systemd[1]: Starting system-ifup.slice. Aug 16 23:46:09 lobbymusic systemd[1]: Created slice system-ifup.slice. Aug 16 23:46:09 lobbymusic kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Aug 16 23:46:09 lobbymusic kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Aug 16 23:46:09 lobbymusic kernel: usbcore: registered new interface driver brcmfmac Aug 16 23:46:09 lobbymusic systemd-fsck[366]: fsck.fat 3.0.27 (2014-11-12) Aug 16 23:46:09 lobbymusic systemd-fsck[366]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Aug 16 23:46:09 lobbymusic systemd-fsck[366]: Automatically removing dirty bit. Aug 16 23:46:10 lobbymusic systemd-fsck[366]: Performing changes. Aug 16 23:46:10 lobbymusic systemd-fsck[366]: /dev/mmcblk0p1: 240 files, 116219/123044 clusters Aug 16 23:46:10 lobbymusic systemd[1]: Started File System Check on /dev/mmcblk0p1. Aug 16 23:46:10 lobbymusic systemd[1]: Mounting /boot... Aug 16 23:46:10 lobbymusic systemd[1]: Mounted /boot. Aug 16 23:46:10 lobbymusic systemd[1]: Starting Local File Systems. Aug 16 23:46:10 lobbymusic systemd[1]: Reached target Local File Systems. Aug 16 23:46:10 lobbymusic systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 16 23:46:10 lobbymusic systemd[1]: Starting Remote File Systems. Aug 16 23:46:10 lobbymusic systemd[1]: Reached target Remote File Systems. Aug 16 23:46:10 lobbymusic systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Aug 16 23:46:10 lobbymusic systemd[1]: Starting Create Volatile Files and Directories... Aug 16 23:46:10 lobbymusic systemd[1]: Starting LSB: Raise network interfaces.... Aug 16 23:46:10 lobbymusic systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 16 23:46:10 lobbymusic kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Aug 16 23:46:10 lobbymusic kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Aug 16 23:46:10 lobbymusic 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 Aug 16 23:46:10 lobbymusic systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Aug 16 23:46:10 lobbymusic systemd[1]: Started Create Volatile Files and Directories. Aug 16 23:46:10 lobbymusic systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 16 23:46:10 lobbymusic systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 16 23:46:10 lobbymusic systemd[1]: Starting system-systemd\x2drfkill.slice. Aug 16 23:46:10 lobbymusic systemd[1]: Created slice system-systemd\x2drfkill.slice. Aug 16 23:46:10 lobbymusic systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Aug 16 23:46:10 lobbymusic systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Aug 16 23:46:11 lobbymusic systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Aug 16 23:46:12 lobbymusic kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Aug 16 23:46:12 lobbymusic kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 16 23:46:12 lobbymusic networking[382]: Configuring network interfaces...dhcpcd[535]: version 6.0.5 starting Aug 16 23:46:12 lobbymusic dhcpcd[535]: version 6.0.5 starting Aug 16 23:46:12 lobbymusic networking[382]: dhcpcd[535]: eth0: soliciting an IPv6 router Aug 16 23:46:12 lobbymusic dhcpcd[535]: eth0: soliciting an IPv6 router Aug 16 23:46:12 lobbymusic dhcpcd[535]: eth0: rebinding lease of 192.168.1.16 Aug 16 23:46:12 lobbymusic networking[382]: dhcpcd[535]: eth0: rebinding lease of 192.168.1.16 Aug 16 23:46:13 lobbymusic dhcpcd[535]: eth0: carrier lost Aug 16 23:46:13 lobbymusic networking[382]: dhcpcd[535]: eth0: carrier lost Aug 16 23:46:13 lobbymusic dhcpcd[535]: eth0: carrier acquired Aug 16 23:46:13 lobbymusic networking[382]: dhcpcd[535]: eth0: carrier acquired Aug 16 23:46:13 lobbymusic kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x43E1 Aug 16 23:46:13 lobbymusic dhcpcd[535]: eth0: soliciting an IPv6 router Aug 16 23:46:13 lobbymusic networking[382]: dhcpcd[535]: eth0: soliciting an IPv6 router Aug 16 23:46:13 lobbymusic networking[382]: dhcpcd[535]: eth0: rebinding lease of 192.168.1.16 Aug 16 23:46:13 lobbymusic dhcpcd[535]: eth0: rebinding lease of 192.168.1.16 Aug 16 23:46:18 lobbymusic dhcpcd[535]: eth0: leased 192.168.1.16 for 3600 seconds Aug 16 23:46:18 lobbymusic dhcpcd[535]: eth0: adding host route to 192.168.1.16 via 127.0.0.1 Aug 16 23:46:18 lobbymusic dhcpcd[535]: eth0: adding route to 192.168.0.0/23 Aug 16 23:46:18 lobbymusic dhcpcd[535]: eth0: adding default route via 192.168.0.180 Aug 16 23:46:18 lobbymusic networking[382]: dhcpcd[535]: eth0: leased 192.168.1.16 for 3600 seconds Aug 16 23:46:18 lobbymusic networking[382]: dhcpcd[535]: eth0: adding host route to 192.168.1.16 via 127.0.0.1 Aug 16 23:46:18 lobbymusic networking[382]: dhcpcd[535]: eth0: adding route to 192.168.0.0/23 Aug 16 23:46:18 lobbymusic networking[382]: dhcpcd[535]: eth0: adding default route via 192.168.0.180 Aug 16 23:46:19 lobbymusic dhcpcd[535]: forked to background, child pid 600 Aug 16 23:46:19 lobbymusic networking[382]: dhcpcd[535]: forked to background, child pid 600 Aug 16 23:46:19 lobbymusic networking[382]: done. Aug 16 23:46:19 lobbymusic systemd[1]: Started LSB: Raise network interfaces.. Aug 16 23:46:19 lobbymusic systemd[1]: Starting ifup for wlan0... Aug 16 23:46:19 lobbymusic systemd[1]: Started ifup for wlan0. Aug 16 23:46:19 lobbymusic systemd[1]: Starting ifup for eth0... Aug 16 23:46:19 lobbymusic systemd[1]: Started ifup for eth0. Aug 16 23:46:19 lobbymusic systemd[1]: Starting Network. Aug 16 23:46:19 lobbymusic systemd[1]: Reached target Network. Aug 16 23:46:19 lobbymusic systemd[1]: Starting Network is Online. Aug 16 23:46:19 lobbymusic systemd[1]: Reached target Network is Online. Aug 16 23:46:19 lobbymusic systemd[1]: Starting LSB: RPC portmapper replacement... Aug 16 23:46:19 lobbymusic ifup[646]: /sbin/ifup: interface wlan0 already configured Aug 16 23:46:19 lobbymusic ifup[647]: /sbin/ifup: interface eth0 already configured Aug 16 23:46:19 lobbymusic rpcbind[649]: Starting rpcbind daemon.... Aug 16 23:46:19 lobbymusic systemd[1]: Started LSB: RPC portmapper replacement. Aug 16 23:46:19 lobbymusic systemd[1]: Starting RPC Port Mapper. Aug 16 23:46:19 lobbymusic systemd[1]: Reached target RPC Port Mapper. Aug 16 23:46:19 lobbymusic systemd[1]: Starting LSB: NFS support files common to client and server... Aug 16 23:46:20 lobbymusic rpc.statd[671]: Version 1.2.8 starting Aug 16 23:46:20 lobbymusic sm-notify[672]: Version 1.2.8 starting Aug 16 23:46:20 lobbymusic kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Aug 16 23:46:20 lobbymusic nfs-common[665]: Starting NFS common utilities: statd idmapd. Aug 16 23:46:20 lobbymusic systemd[1]: Started LSB: NFS support files common to client and server. Aug 16 23:46:20 lobbymusic systemd[1]: Starting System Initialization. Aug 16 23:46:20 lobbymusic systemd[1]: Reached target System Initialization. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Aug 16 23:46:20 lobbymusic systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 16 23:46:20 lobbymusic systemd[1]: Starting mpd.socket. Aug 16 23:46:20 lobbymusic systemd[1]: Listening on mpd.socket. Aug 16 23:46:20 lobbymusic systemd[1]: Starting D-Bus System Message Bus Socket. Aug 16 23:46:20 lobbymusic systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Sockets. Aug 16 23:46:20 lobbymusic systemd[1]: Reached target Sockets. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Daily Cleanup of Temporary Directories. Aug 16 23:46:20 lobbymusic systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Timers. Aug 16 23:46:20 lobbymusic systemd[1]: Reached target Timers. Aug 16 23:46:20 lobbymusic systemd[1]: Started Manage Sound Card State (restore and store). Aug 16 23:46:20 lobbymusic systemd[1]: Starting Restore Sound Card State... Aug 16 23:46:20 lobbymusic systemd[1]: Starting Basic System. Aug 16 23:46:20 lobbymusic systemd[1]: Reached target Basic System. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Aug 16 23:46:20 lobbymusic systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Configure Bluetooth Modems connected by UART... Aug 16 23:46:20 lobbymusic systemd[1]: Starting Volumio Iptables Module... Aug 16 23:46:20 lobbymusic systemd[1]: Started Volumio Iptables Module. Aug 16 23:46:20 lobbymusic systemd[1]: Starting mount disks automatically with standby... Aug 16 23:46:20 lobbymusic systemd[1]: Started mount disks automatically with standby. Aug 16 23:46:20 lobbymusic systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 16 23:46:20 lobbymusic systemd[1]: Started UPnP Renderer front-end to MPD. Aug 16 23:46:20 lobbymusic systemd[1]: Starting volumio-remote-updater.service... Aug 16 23:46:20 lobbymusic systemd[1]: Started volumio-remote-updater.service. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Volumio Log Rotation Service... Aug 16 23:46:20 lobbymusic systemd[1]: Started Volumio Log Rotation Service. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Volumio SSH enabler... Aug 16 23:46:20 lobbymusic systemd[1]: Started Volumio SSH enabler. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Wireless Services... Aug 16 23:46:20 lobbymusic systemd[1]: Starting OpenBSD Secure Shell server... Aug 16 23:46:20 lobbymusic kernel: random: crng init done Aug 16 23:46:20 lobbymusic kernel: random: 7 urandom warning(s) missed due to ratelimiting Aug 16 23:46:20 lobbymusic systemd[1]: Starting /etc/rc.local Compatibility... Aug 16 23:46:20 lobbymusic systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Aug 16 23:46:20 lobbymusic systemd[1]: Starting Login Service... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: start Winbind daemon... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: triggerhappy hotkey daemon... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: start Samba daemons for the AD DC... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: Start NTP daemon... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: Brings up/down network automatically... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Aug 16 23:46:20 lobbymusic systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Aug 16 23:46:20 lobbymusic systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 16 23:46:20 lobbymusic systemd[1]: Starting Music Player Daemon... Aug 16 23:46:20 lobbymusic systemd[1]: Starting D-Bus System Message Bus... Aug 16 23:46:20 lobbymusic systemd[1]: Started D-Bus System Message Bus. Aug 16 23:46:20 lobbymusic kernel: uart-pl011 3f201000.serial: no DMA platform data Aug 16 23:46:20 lobbymusic dhcpcd[746]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Aug 16 23:46:20 lobbymusic dhcpcd[746]: /var/run/dhcpcd-eth0.pid ... failed! Aug 16 23:46:20 lobbymusic ifplugd(eth0)[812]: ifplugd 0.28 initializing. Aug 16 23:46:21 lobbymusic ifplugd(eth0)[812]: Using interface eth0/B8:27:EB:51:E5:16 with driver (version: 22-Aug-2005) Aug 16 23:46:21 lobbymusic ifplugd(eth0)[812]: Using detection mode: SIOCETHTOOL Aug 16 23:46:21 lobbymusic ifplugd(eth0)[812]: Initialization complete, link beat detected. Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Unable to parse trigger line: Aug 16 23:46:21 lobbymusic triggerhappy[710]: Error opening '/dev/input/event*': No such file or directory Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Successfully dropped root privileges. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: avahi-daemon 0.6.31 starting up. Aug 16 23:46:21 lobbymusic loadcpufreq[723]: Loading cpufreq kernel modules...done (none). Aug 16 23:46:21 lobbymusic ifplugd(eth0)[812]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Successfully called chroot(). Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Successfully dropped remaining capabilities. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Loading service file /services/volumio.service. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.16. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: New relevant interface eth0.IPv4 for mDNS. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Network interface enumeration completed. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Registering new address record for 192.168.1.16 on eth0.IPv4. Aug 16 23:46:21 lobbymusic avahi-daemon[750]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Aug 16 23:46:21 lobbymusic systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 16 23:46:21 lobbymusic systemd[1]: Starting Permit User Sessions... Aug 16 23:46:21 lobbymusic systemd[1]: Started Restore Sound Card State. Aug 16 23:46:21 lobbymusic ifplugd(eth0)[812]: client: /sbin/ifup: interface eth0 already configured Aug 16 23:46:21 lobbymusic systemd[1]: Started /etc/rc.local Compatibility. Aug 16 23:46:21 lobbymusic systemd[1]: Started LSB: triggerhappy hotkey daemon. Aug 16 23:46:21 lobbymusic systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 16 23:46:21 lobbymusic systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Aug 16 23:46:21 lobbymusic systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Aug 16 23:46:21 lobbymusic systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Aug 16 23:46:21 lobbymusic systemd[1]: Unit dhcpcd.service entered failed state. Aug 16 23:46:21 lobbymusic systemd[1]: Started Permit User Sessions. Aug 16 23:46:21 lobbymusic ifplugd(eth0)[812]: Program executed successfully. Aug 16 23:46:21 lobbymusic systemd[1]: Started LSB: Brings up/down network automatically. Aug 16 23:46:21 lobbymusic ifplugd[732]: Network Interface Plugging Daemon...start eth0...done. Aug 16 23:46:21 lobbymusic systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 16 23:46:21 lobbymusic systemd-logind[707]: New seat seat0. Aug 16 23:46:21 lobbymusic systemd[1]: Starting Hold until boot process finishes up... Aug 16 23:46:21 lobbymusic systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 16 23:46:21 lobbymusic ntpd[792]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Aug 16 23:46:21 lobbymusic ntp[719]: Starting NTP server: ntpd. Aug 16 23:46:21 lobbymusic systemd[1]: Received SIGRTMIN+21 from PID 244 (plymouthd). Aug 16 23:46:21 lobbymusic systemd[1]: Started LSB: Start NTP daemon. Aug 16 23:46:21 lobbymusic systemd[1]: Started Login Service. Aug 16 23:46:21 lobbymusic systemd[1]: Started Hold until boot process finishes up. Aug 16 23:46:21 lobbymusic systemd[1]: Started Terminate Plymouth Boot Screen. Aug 16 23:46:21 lobbymusic systemd[1]: Starting Getty on tty1... Aug 16 23:46:21 lobbymusic ntpd[846]: proto: precision = 0.417 usec Aug 16 23:46:21 lobbymusic systemd[1]: Started Getty on tty1. Aug 16 23:46:21 lobbymusic systemd[1]: Starting Login Prompts. Aug 16 23:46:21 lobbymusic systemd[1]: Reached target Login Prompts. Aug 16 23:46:21 lobbymusic ntpd[846]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Aug 16 23:46:21 lobbymusic dbus[760]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Aug 16 23:46:21 lobbymusic cpufrequtils[837]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 16 23:46:21 lobbymusic ntpd[846]: Listen and drop on 1 v6wildcard :: UDP 123 Aug 16 23:46:21 lobbymusic ntpd[846]: Listen normally on 2 lo 127.0.0.1 UDP 123 Aug 16 23:46:21 lobbymusic ntpd[846]: Listen normally on 3 eth0 192.168.1.16 UDP 123 Aug 16 23:46:21 lobbymusic ntpd[846]: peers refreshed Aug 16 23:46:21 lobbymusic ntpd[846]: Listening on routing socket on fd #20 for interface updates Aug 16 23:46:21 lobbymusic systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 16 23:46:21 lobbymusic systemd[1]: Starting Disk Manager (legacy version)... Aug 16 23:46:21 lobbymusic sudo[708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Aug 16 23:46:21 lobbymusic sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:21 lobbymusic dbus[760]: [system] Successfully activated service 'org.freedesktop.UDisks' Aug 16 23:46:21 lobbymusic systemd[1]: Started Disk Manager (legacy version). Aug 16 23:46:21 lobbymusic sudo[708]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:21 lobbymusic systemd[1]: Started OpenBSD Secure Shell server. Aug 16 23:46:21 lobbymusic sshd[869]: Server listening on 0.0.0.0 port 22. Aug 16 23:46:21 lobbymusic sshd[869]: Server listening on :: port 22. Aug 16 23:46:22 lobbymusic dbus[760]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Aug 16 23:46:22 lobbymusic systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Aug 16 23:46:22 lobbymusic polkitd[878]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 16 23:46:22 lobbymusic dbus[760]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 16 23:46:22 lobbymusic systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Aug 16 23:46:22 lobbymusic avahi-daemon[750]: Server startup complete. Host name is lobbymusic.local. Local service cookie is 1155843651. Aug 16 23:46:22 lobbymusic udisks-glue[693]: Device file /dev/mmcblk0 inserted Aug 16 23:46:22 lobbymusic udisks-glue[693]: Device file /dev/mmcblk0p3 inserted Aug 16 23:46:22 lobbymusic haveged[690]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Aug 16 23:46:22 lobbymusic haveged[690]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Aug 16 23:46:22 lobbymusic haveged[690]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00105 Aug 16 23:46:22 lobbymusic haveged[690]: haveged: fills: 0, generated: 0 Aug 16 23:46:22 lobbymusic volumio-remote-updater[698]: Error: No active session Aug 16 23:46:22 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:22] [info] asio async_connect error: system:111 (Connection refused) Aug 16 23:46:22 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 16 23:46:22 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:22] [error] handle_connect error: Underlying Transport Error Aug 16 23:46:22 lobbymusic volumio[694]: Could not open config: /tmp/upmpdcli.conf Aug 16 23:46:22 lobbymusic systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Aug 16 23:46:22 lobbymusic systemd[1]: Unit upmpdcli.service entered failed state. Aug 16 23:46:23 lobbymusic avahi-daemon[750]: Service "LobbyMusic" (/services/volumio.service) successfully established. Aug 16 23:46:25 lobbymusic systemd[1]: Started Music Player Daemon. Aug 16 23:46:25 lobbymusic wireless.js[876]: Cleaning previous... Aug 16 23:46:25 lobbymusic systemd[1]: Stopped hotspot.service. Aug 16 23:46:25 lobbymusic sudo[907]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 16 23:46:25 lobbymusic sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:25 lobbymusic systemd[1]: Started LSB: start Samba daemons for the AD DC. Aug 16 23:46:25 lobbymusic sudo[907]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:25 lobbymusic sudo[924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 16 23:46:25 lobbymusic sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:25 lobbymusic winbind[709]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Aug 16 23:46:25 lobbymusic winbind[709]: Unable to setup corepath for winbindd: No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: mkdir failed on directory /var/log/samba/cores: No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Aug 16 23:46:25 lobbymusic winbind[709]: Unable to setup corepath for winbindd: No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbind[709]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbind[709]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25, 0] ../source3/winbindd/winbindd.c:1549(main) Aug 16 23:46:25 lobbymusic winbind[709]: winbindd version 4.2.14-Debian started. Aug 16 23:46:25 lobbymusic winbind[709]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25, 0] ../lib/util/util.c:220(directory_create_or_exist) Aug 16 23:46:25 lobbymusic winbind[709]: mkdir failed on directory /var/log/samba/cores: No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Aug 16 23:46:25 lobbymusic winbind[709]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Aug 16 23:46:25 lobbymusic winbind[709]: Unable to setup corepath for winbindd: No such file or directory Aug 16 23:46:25 lobbymusic winbindd[922]: [2022/08/16 23:46:25.421589, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbindd[922]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Aug 16 23:46:25 lobbymusic winbindd[922]: [2022/08/16 23:46:25.422606, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbindd[922]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[931]: [2022/08/16 23:46:25.426845, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic nmbd[931]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[931]: [2022/08/16 23:46:25.428705, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic nmbd[931]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 16 23:46:25 lobbymusic kernel: Bluetooth: Core ver 2.22 Aug 16 23:46:25 lobbymusic kernel: NET: Registered protocol family 31 Aug 16 23:46:25 lobbymusic kernel: Bluetooth: HCI device and connection manager initialized Aug 16 23:46:25 lobbymusic kernel: Bluetooth: HCI socket layer initialized Aug 16 23:46:25 lobbymusic kernel: Bluetooth: L2CAP socket layer initialized Aug 16 23:46:25 lobbymusic kernel: Bluetooth: SCO socket layer initialized Aug 16 23:46:25 lobbymusic nmbd[936]: [2022/08/16 23:46:25.432259, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Aug 16 23:46:25 lobbymusic nmbd[936]: started asyncdns process 937 Aug 16 23:46:25 lobbymusic systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Aug 16 23:46:25 lobbymusic systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25.421589, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbind[709]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Aug 16 23:46:25 lobbymusic winbind[709]: [2022/08/16 23:46:25.422606, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbind[709]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Aug 16 23:46:25 lobbymusic nmbd[720]: Unable to setup corepath for nmbd: No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: [2022/08/16 23:46:25, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic nmbd[720]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: [2022/08/16 23:46:25, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic nmbd[720]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: [2022/08/16 23:46:25, 0] ../source3/nmbd/nmbd.c:908(main) Aug 16 23:46:25 lobbymusic nmbd[720]: nmbd version 4.2.14-Debian started. Aug 16 23:46:25 lobbymusic nmbd[720]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Aug 16 23:46:25 lobbymusic nmbd[720]: [2022/08/16 23:46:25.426845, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic nmbd[720]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: [2022/08/16 23:46:25.428705, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic nmbd[720]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Aug 16 23:46:25 lobbymusic nmbd[720]: . Aug 16 23:46:25 lobbymusic btuart[691]: bcm43xx_init Aug 16 23:46:25 lobbymusic btuart[691]: Flash firmware /lib/firmware/BCM43430A1.hcd Aug 16 23:46:25 lobbymusic btuart[691]: Set BDADDR UART: b8:27:eb:fb:4f:bc Aug 16 23:46:25 lobbymusic btuart[691]: Set Controller UART speed to 460800 bit/s Aug 16 23:46:25 lobbymusic btuart[691]: Device setup complete Aug 16 23:46:25 lobbymusic systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 16 23:46:25 lobbymusic systemd-udevd[947]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory Aug 16 23:46:25 lobbymusic kernel: Bluetooth: HCI UART driver ver 2.3 Aug 16 23:46:25 lobbymusic kernel: Bluetooth: HCI UART protocol H4 registered Aug 16 23:46:25 lobbymusic kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 16 23:46:25 lobbymusic kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 16 23:46:25 lobbymusic systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Aug 16 23:46:25 lobbymusic systemd[1]: Starting Bluetooth service... Aug 16 23:46:25 lobbymusic systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Aug 16 23:46:25 lobbymusic bluetoothd[951]: Bluetooth daemon 5.23 Aug 16 23:46:25 lobbymusic bluetoothd[951]: Starting SDP server Aug 16 23:46:25 lobbymusic bluetoothd[951]: Excluding (cli) sap Aug 16 23:46:25 lobbymusic systemd[1]: Started Bluetooth service. Aug 16 23:46:25 lobbymusic systemd[1]: Starting Bluetooth. Aug 16 23:46:25 lobbymusic systemd[1]: Reached target Bluetooth. Aug 16 23:46:25 lobbymusic dbus[760]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Aug 16 23:46:25 lobbymusic bluetoothd[951]: Bluetooth management interface 1.14 initialized Aug 16 23:46:25 lobbymusic kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 16 23:46:25 lobbymusic kernel: Bluetooth: BNEP filters: protocol multicast Aug 16 23:46:25 lobbymusic kernel: Bluetooth: BNEP socket layer initialized Aug 16 23:46:25 lobbymusic systemd[1]: Starting Hostname Service... Aug 16 23:46:25 lobbymusic sudo[924]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:25 lobbymusic nmbd[936]: [2022/08/16 23:46:25.796154, 0] ../lib/util/become_daemon.c:124(daemon_ready) Aug 16 23:46:25 lobbymusic nmbd[936]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 16 23:46:25 lobbymusic winbindd[960]: [2022/08/16 23:46:25.809790, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Aug 16 23:46:25 lobbymusic winbindd[960]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 16 23:46:25 lobbymusic systemd[1]: Started LSB: start Winbind daemon. Aug 16 23:46:25 lobbymusic winbind[709]: . Aug 16 23:46:25 lobbymusic winbindd[960]: [2022/08/16 23:46:25.857489, 0] ../lib/util/become_daemon.c:124(daemon_ready) Aug 16 23:46:25 lobbymusic winbindd[960]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 16 23:46:25 lobbymusic systemd-hostnamed[954]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Aug 16 23:46:25 lobbymusic winbindd[964]: [2022/08/16 23:46:25.864078, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:25 lobbymusic winbindd[964]: Unable to open new log file '/var/log/samba/log.wb-LOBBYMUSIC': No such file or directory Aug 16 23:46:25 lobbymusic dbus[760]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 16 23:46:25 lobbymusic systemd[1]: Started Hostname Service. Aug 16 23:46:25 lobbymusic wireless.js[876]: Stopped aP Aug 16 23:46:25 lobbymusic wireless.js[876]: Start wireless flow Aug 16 23:46:25 lobbymusic wireless.js[876]: Stopped hotspot (if there).. Aug 16 23:46:25 lobbymusic sudo[968]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 16 23:46:25 lobbymusic sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:25 lobbymusic sudo[968]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:25 lobbymusic sudo[976]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 16 23:46:25 lobbymusic sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:25 lobbymusic sudo[976]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:26 lobbymusic wireless.js[876]: DHCP IP Aug 16 23:46:26 lobbymusic wireless.js[876]: Start ap Aug 16 23:46:26 lobbymusic sudo[985]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 16 23:46:26 lobbymusic sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:26 lobbymusic dhcpcd[989]: version 6.0.5 starting Aug 16 23:46:26 lobbymusic kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 16 23:46:26 lobbymusic wpa_supplicant[984]: Successfully initialized wpa_supplicant Aug 16 23:46:26 lobbymusic dhcpcd[989]: eth0: soliciting an IPv6 router Aug 16 23:46:26 lobbymusic dhcpcd[989]: eth0: rebinding lease of 192.168.1.16 Aug 16 23:46:26 lobbymusic dhcpcd[989]: wlan0: waiting for carrier Aug 16 23:46:26 lobbymusic dhcpcd[989]: eth0: leased 192.168.1.16 for 28800 seconds Aug 16 23:46:26 lobbymusic dhcpcd[989]: eth0: adding host route to 192.168.1.16 via 127.0.0.1 Aug 16 23:46:26 lobbymusic dhcpcd[989]: eth0: adding route to 192.168.0.0/23 Aug 16 23:46:26 lobbymusic dhcpcd[989]: eth0: adding default route via 192.168.0.180 Aug 16 23:46:26 lobbymusic dhcpcd[600]: eth0: removing route to 192.168.0.0/23 Aug 16 23:46:26 lobbymusic dhcpcd[600]: eth0: removing default route via 192.168.0.180 Aug 16 23:46:26 lobbymusic dhcpcd[1046]: eth0: removing route to 192.168.0.0/23 Aug 16 23:46:26 lobbymusic dhcpcd[1046]: eth0: removing default route via 192.168.0.180 Aug 16 23:46:26 lobbymusic dhcpcd[989]: forked to background, child pid 1046 Aug 16 23:46:26 lobbymusic sudo[985]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:26 lobbymusic smbd[938]: Starting SMB/CIFS daemon: smbd[2022/08/16 23:46:26, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[938]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26, 0] ../lib/util/util.c:220(directory_create_or_exist) Aug 16 23:46:26 lobbymusic smbd[938]: mkdir failed on directory /var/log/samba/cores: No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Aug 16 23:46:26 lobbymusic smbd[938]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Aug 16 23:46:26 lobbymusic smbd[938]: Unable to setup corepath for smbd: No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[938]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26, 0] ../source3/smbd/server.c:1241(main) Aug 16 23:46:26 lobbymusic smbd[938]: smbd version 4.2.14-Debian started. Aug 16 23:46:26 lobbymusic smbd[938]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Aug 16 23:46:26 lobbymusic smbd[1023]: [2022/08/16 23:46:26.397525, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[1023]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[1023]: [2022/08/16 23:46:26.398888, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[1023]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[1023]: [2022/08/16 23:46:26.399084, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[1023]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26.397525, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[938]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26.398888, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[938]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: [2022/08/16 23:46:26.399084, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[938]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic smbd[938]: . Aug 16 23:46:26 lobbymusic systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Aug 16 23:46:26 lobbymusic winbindd[1053]: [2022/08/16 23:46:26.452730, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic winbindd[1053]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Aug 16 23:46:26 lobbymusic smbd[1050]: [2022/08/16 23:46:26.484459, 0] ../lib/util/become_daemon.c:124(daemon_ready) Aug 16 23:46:26 lobbymusic smbd[1050]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 16 23:46:26 lobbymusic smbd[1054]: [2022/08/16 23:46:26.488929, 0] ../lib/util/debug.c:603(reopen_logs_internal) Aug 16 23:46:26 lobbymusic smbd[1054]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Aug 16 23:46:26 lobbymusic wpa_supplicant[1027]: wlan0: Trying to associate with SSID 'Bandos' Aug 16 23:46:27 lobbymusic wireless.js[876]: trying... Aug 16 23:46:27 lobbymusic sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:27 lobbymusic sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:27 lobbymusic sudo[1058]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:27 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:27] [info] asio async_connect error: system:111 (Connection refused) Aug 16 23:46:27 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 16 23:46:27 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:27] [error] handle_connect error: Underlying Transport Error Aug 16 23:46:27 lobbymusic wpa_supplicant[1027]: wlan0: Associated with 74:ac:b9:94:9f:71 Aug 16 23:46:27 lobbymusic wpa_supplicant[1027]: wlan0: CTRL-EVENT-CONNECTED - Connection to 74:ac:b9:94:9f:71 completed [id=0 id_str=] Aug 16 23:46:27 lobbymusic dhcpcd[1046]: wlan0: carrier acquired Aug 16 23:46:27 lobbymusic dhcpcd[1046]: wlan0: soliciting an IPv6 router Aug 16 23:46:27 lobbymusic dhcpcd[1046]: wlan0: rebinding lease of 10.20.35.94 Aug 16 23:46:28 lobbymusic wireless.js[876]: trying... Aug 16 23:46:28 lobbymusic sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:28 lobbymusic sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:28 lobbymusic sudo[1084]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:29 lobbymusic wireless.js[876]: trying... Aug 16 23:46:29 lobbymusic sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:29 lobbymusic sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:29 lobbymusic sudo[1093]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:30 lobbymusic wireless.js[876]: trying... Aug 16 23:46:30 lobbymusic sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:30 lobbymusic sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:30 lobbymusic sudo[1102]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:31 lobbymusic wireless.js[876]: trying... Aug 16 23:46:31 lobbymusic sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:31 lobbymusic sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:31 lobbymusic sudo[1111]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:32 lobbymusic wireless.js[876]: trying... Aug 16 23:46:32 lobbymusic sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:32 lobbymusic sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:32 lobbymusic sudo[1120]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:32 lobbymusic dhcpcd[1046]: wlan0: leased 10.20.35.94 for 3600 seconds Aug 16 23:46:32 lobbymusic avahi-daemon[750]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.20.35.94. Aug 16 23:46:32 lobbymusic avahi-daemon[750]: New relevant interface wlan0.IPv4 for mDNS. Aug 16 23:46:32 lobbymusic dhcpcd[1046]: eth0: adding route to 192.168.0.0/23 Aug 16 23:46:32 lobbymusic avahi-daemon[750]: Registering new address record for 10.20.35.94 on wlan0.IPv4. Aug 16 23:46:32 lobbymusic dhcpcd[1046]: eth0: adding default route via 192.168.0.180 Aug 16 23:46:32 lobbymusic dhcpcd[1046]: wlan0: adding host route to 10.20.35.94 via 127.0.0.1 Aug 16 23:46:32 lobbymusic dhcpcd[1046]: wlan0: adding route to 10.20.32.0/22 Aug 16 23:46:32 lobbymusic dhcpcd[1046]: wlan0: adding default route via 10.20.35.254 Aug 16 23:46:33 lobbymusic dhcpcd[1046]: eth0: removing route to 192.168.0.0/23 Aug 16 23:46:33 lobbymusic dhcpcd[1046]: eth0: removing default route via 192.168.0.180 Aug 16 23:46:33 lobbymusic wireless.js[876]: trying... Aug 16 23:46:33 lobbymusic sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 16 23:46:33 lobbymusic sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:33 lobbymusic sudo[1151]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:33 lobbymusic wireless.js[876]: Connected to: ----Bandos Aug 16 23:46:33 lobbymusic wireless.js[876]: ---- Aug 16 23:46:33 lobbymusic sudo[1160]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 16 23:46:33 lobbymusic sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:33 lobbymusic sudo[1160]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:33 lobbymusic wireless.js[876]: ... joined AP, wlan0 IPv4 is 10.20.35.94, ipV6 is undefined Aug 16 23:46:33 lobbymusic wireless.js[876]: It's done! AP Aug 16 23:46:33 lobbymusic systemd[1]: Started Wireless Services. Aug 16 23:46:33 lobbymusic systemd[1]: Starting Volumio Backend Module... Aug 16 23:46:33 lobbymusic systemd[1]: Started Volumio Backend Module. Aug 16 23:46:33 lobbymusic systemd[1]: Starting Volumio Streaming Daemon... Aug 16 23:46:33 lobbymusic systemd[1]: Started Volumio Streaming Daemon. Aug 16 23:46:33 lobbymusic systemd[1]: Starting Multi-User System. Aug 16 23:46:33 lobbymusic systemd[1]: Reached target Multi-User System. Aug 16 23:46:33 lobbymusic systemd[1]: Starting Graphical Interface. Aug 16 23:46:33 lobbymusic systemd[1]: Reached target Graphical Interface. Aug 16 23:46:33 lobbymusic systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 16 23:46:33 lobbymusic systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 16 23:46:33 lobbymusic systemd[1]: Startup finished in 9.478s (kernel) + 26.153s (userspace) = 35.632s. Aug 16 23:46:33 lobbymusic volumio-streaming-daemon[1170]: ############################ Aug 16 23:46:33 lobbymusic volumio-streaming-daemon[1170]: # Volumio Streaming Daemon # Aug 16 23:46:33 lobbymusic volumio-streaming-daemon[1170]: # Running on port 7777 # Aug 16 23:46:33 lobbymusic volumio-streaming-daemon[1170]: ############################ Aug 16 23:46:33 lobbymusic volumio-streaming-daemon[1170]: Environment: production Aug 16 23:46:34 lobbymusic ntpd[846]: Listen normally on 4 wlan0 10.20.35.94 UDP 123 Aug 16 23:46:34 lobbymusic ntpd[846]: peers refreshed Aug 16 23:46:34 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:34] [info] asio async_connect error: system:111 (Connection refused) Aug 16 23:46:34 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 16 23:46:34 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:34] [error] handle_connect error: Underlying Transport Error Aug 16 23:46:36 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:36 lobbymusic volumio[1169]: info: ----- Volumio2 ---- Aug 16 23:46:36 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:36 lobbymusic volumio[1169]: info: ----- System startup ---- Aug 16 23:46:36 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:37 lobbymusic volumio[1169]: info: MYVOLUMIO Environment detected Aug 16 23:46:37 lobbymusic volumio[1169]: info: Plugin folders cleanup Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning into folder /volumio/app/plugins/ Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category audio_interface Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category miscellanea Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category music_service Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category plugins.json Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category system_controller Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category user_interface Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning into folder /data/plugins/ Aug 16 23:46:37 lobbymusic volumio[1169]: info: Scanning category miscellanea Aug 16 23:46:37 lobbymusic volumio[1169]: info: Plugin folders cleanup completed Aug 16 23:46:37 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:37 lobbymusic volumio[1169]: info: ----- Core plugins startup ---- Aug 16 23:46:37 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:37 lobbymusic volumio[1169]: info: Loading plugins from folder /volumio/app/plugins/ Aug 16 23:46:37 lobbymusic volumio[1169]: info: Adding plugin upnp to MyMusic Plugins Aug 16 23:46:37 lobbymusic volumio[1169]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 16 23:46:37 lobbymusic volumio[1169]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 16 23:46:37 lobbymusic volumio[1169]: info: Loading plugins from folder /data/plugins/ Aug 16 23:46:37 lobbymusic volumio[1169]: info: Loading plugin "system"... Aug 16 23:46:37 lobbymusic volumio[1169]: info: Loading plugin "appearance"... Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "network"... Aug 16 23:46:40 lobbymusic volumio[1169]: info: Refreshing Cached IP Addresses Aug 16 23:46:40 lobbymusic sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "services"... Aug 16 23:46:40 lobbymusic sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "alsa_controller"... Aug 16 23:46:40 lobbymusic sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 16 23:46:40 lobbymusic sudo[1195]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:40 lobbymusic sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:40 lobbymusic sudo[1198]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "wizard"... Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "volumio_command_line_client"... Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "upnp"... Aug 16 23:46:40 lobbymusic volumio[1169]: info: [1660693600546] Starting Upmpd Daemon Aug 16 23:46:40 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "my_music"... Aug 16 23:46:40 lobbymusic volumio[1169]: info: Loading plugin "mpd"... Aug 16 23:46:41 lobbymusic volumio[1169]: info: Loading plugin "upnp_browser"... Aug 16 23:46:41 lobbymusic volumio[1169]: info: Loading plugin "networkfs"... Aug 16 23:46:41 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 16 23:46:41 lobbymusic volumio[1169]: info: Loading plugin "alarm-clock"... Aug 16 23:46:41 lobbymusic sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=music,password=1Q2w3e4r,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.235/music\Reception /mnt/NAS/Lobby_NAS Aug 16 23:46:41 lobbymusic sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:42 lobbymusic kernel: FS-Cache: Netfs 'cifs' registered for caching Aug 16 23:46:42 lobbymusic kernel: Key type cifs.spnego registered Aug 16 23:46:42 lobbymusic kernel: Key type cifs.idmap registered Aug 16 23:46:42 lobbymusic kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "airplay_emulation"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Starting Shairport Sync Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "last_100"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "webradio"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "i2s_dacs"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: I2S DAC not set, start Auto-detection Aug 16 23:46:42 lobbymusic sudo[1221]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "volumiodiscovery"... Aug 16 23:46:42 lobbymusic volumio[1169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 16 23:46:42 lobbymusic node[1169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 16 23:46:42 lobbymusic node[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 16 23:46:42 lobbymusic node[1169]: *** WARNING *** For more information see Aug 16 23:46:42 lobbymusic node[1169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 16 23:46:42 lobbymusic node[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 16 23:46:42 lobbymusic node[1169]: *** WARNING *** For more information see Aug 16 23:46:42 lobbymusic volumio[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 16 23:46:42 lobbymusic volumio[1169]: *** WARNING *** For more information see Aug 16 23:46:42 lobbymusic volumio[1169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 16 23:46:42 lobbymusic volumio[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 16 23:46:42 lobbymusic volumio[1169]: *** WARNING *** For more information see Aug 16 23:46:42 lobbymusic volumio[1169]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 16 23:46:42 lobbymusic volumio[1169]: Discovery: StartAdv! undefined Aug 16 23:46:42 lobbymusic volumio[1169]: Discovery: Started advertising... lobbymusic - undefined Aug 16 23:46:42 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "autostart"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Applying required configuration parameters for plugin autostart Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "outputs"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "albumart"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Plugin example_plugin is not enabled Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "inputs"... Aug 16 23:46:42 lobbymusic volumio[1169]: info: Loading plugin "updater_comm"... Aug 16 23:46:43 lobbymusic volumio[1169]: info: Plugin mpdemulation is not enabled Aug 16 23:46:43 lobbymusic volumio[1169]: info: Loading plugin "rest_api"... Aug 16 23:46:43 lobbymusic volumio[1169]: info: Loading plugin "websocket"... Aug 16 23:46:43 lobbymusic volumio[1169]: info: ___________ START PLUGINS ___________ Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 16 23:46:43 lobbymusic volumio[1169]: info: [1660693603140] CoreMusicLibrary::Adding element Media Servers Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 23:46:43 lobbymusic volumio[1169]: Forking 3 albumart workers Aug 16 23:46:43 lobbymusic volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 16 23:46:43 lobbymusic volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 16 23:46:43 lobbymusic volumio[1169]: info: [1660693603280] CoreMusicLibrary::Adding element Last_100 Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 16 23:46:43 lobbymusic volumio[1169]: info: [1660693603285] CoreMusicLibrary::Adding element Webradio Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 16 23:46:43 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:43 lobbymusic volumio[1169]: info: ----- MyVolumio plugins startup ---- Aug 16 23:46:43 lobbymusic volumio[1169]: info: ------------------------------------------- Aug 16 23:46:43 lobbymusic volumio[1169]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 16 23:46:43 lobbymusic volumio[1169]: info: Loading i18n strings for locale en Aug 16 23:46:43 lobbymusic volumio[1169]: Updating browse sources language Aug 16 23:46:43 lobbymusic volumio[1169]: Cannot find translation for sourceMedia Servers Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::initPlayerControls Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 23:46:43 lobbymusic volumio[1169]: info: BOOT COMPLETED Aug 16 23:46:43 lobbymusic volumio[1169]: [Metrics] CommandRouter: 7s 443.38ms Aug 16 23:46:43 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 23:46:44 lobbymusic volumio[1169]: Starting albumart workers Aug 16 23:46:44 lobbymusic volumio[1169]: Starting albumart workers Aug 16 23:46:44 lobbymusic volumio[1169]: Starting albumart workers Aug 16 23:46:44 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:44] [connect] Successful connection Aug 16 23:46:48 lobbymusic volumio[1169]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 16 23:46:48 lobbymusic volumio[1169]: info: CoreCommandRouter::Close All Modals sent Aug 16 23:46:48 lobbymusic volumio[1169]: info: CoreCommandRouter::Close All Modals sent Aug 16 23:46:48 lobbymusic volumio[1169]: Express server listening on port 3000 Aug 16 23:46:48 lobbymusic volumio[1169]: [Metrics] WebUI: 13s 509.05ms Aug 16 23:46:48 lobbymusic volumio[1169]: Volumio Calling Home Aug 16 23:46:48 lobbymusic volumio[1169]: info: CoreStateMachine::resetVolumioState Aug 16 23:46:48 lobbymusic volumio[1169]: info: CoreStateMachine::getcurrentVolume Aug 16 23:46:48 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioRetrievevolume Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 23:46:49 lobbymusic volumio[1169]: info: Setting Device type: Raspberry PI Aug 16 23:46:49 lobbymusic volumio[1169]: info: MPD running with PID757 Aug 16 23:46:49 lobbymusic volumio[1169]: ,establishing connection Aug 16 23:46:49 lobbymusic sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Aug 16 23:46:49 lobbymusic sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:49 lobbymusic sudo[1342]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:49 lobbymusic volumio-remote-updater[698]: [2022-08-16 23:46:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1660693604 101 Aug 16 23:46:49 lobbymusic volumio[1169]: info: VolumeController:: Volume=75 Mute =false Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:46:49 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreStateMachine::updateTrackBlock Aug 16 23:46:49 lobbymusic volumio[1169]: info: CorePlayQueue::getTrackBlock Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioRetrievevolume Aug 16 23:46:49 lobbymusic volumio[1169]: info: Volumio called home Aug 16 23:46:49 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 23:46:49 lobbymusic volumio[1169]: info: Reloading queue from file Aug 16 23:46:49 lobbymusic volumio[1169]: error: updateQueue error: null Aug 16 23:46:49 lobbymusic volumio[1169]: error: Failed LSINFO: Error: [50@0] {} No such directory Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreStateMachine::setRepeat true single undefined Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreStateMachine::setRandom null Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:46:50 lobbymusic volumio[1169]: info: VolumeController:: Volume=75 Mute =false Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:50 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:50 lobbymusic volumio[1169]: info: Starting Shairport Sync Aug 16 23:46:50 lobbymusic volumio[1169]: info: Starting Shairport Sync Aug 16 23:46:50 lobbymusic sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 16 23:46:50 lobbymusic sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:50 lobbymusic sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 16 23:46:50 lobbymusic sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:50 lobbymusic systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 16 23:46:50 lobbymusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 16 23:46:50 lobbymusic sudo[1358]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:50 lobbymusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 16 23:46:50 lobbymusic systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 16 23:46:50 lobbymusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 16 23:46:50 lobbymusic sudo[1360]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:50 lobbymusic volumio[1169]: Discovery: adding eb6727b3-81a4-4847-aa0a-2f28de1a2a94 Aug 16 23:46:50 lobbymusic volumio[1169]: info: mDNS: Found device sbmusic Aug 16 23:46:50 lobbymusic volumio[1169]: info: Shairport-Sync Started Aug 16 23:46:50 lobbymusic volumio[1169]: Error adding Membership: Error: addMembership EINVAL Aug 16 23:46:50 lobbymusic volumio[1169]: info: Shairport-Sync Started Aug 16 23:46:50 lobbymusic sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 16 23:46:50 lobbymusic sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:50 lobbymusic sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 16 23:46:50 lobbymusic sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:50 lobbymusic volumio[1169]: Discovery: adding 54cac4ca-7430-4c4b-8fa9-d919a54cd4e1 Aug 16 23:46:50 lobbymusic volumio[1169]: info: mDNS: Found device poolbarmusic Aug 16 23:46:50 lobbymusic sudo[1390]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:50 lobbymusic sudo[1392]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:50 lobbymusic volumio[1169]: Discovery: adding 1401f839-d5f8-46ff-893a-3304dee59206 Aug 16 23:46:50 lobbymusic volumio[1169]: info: mDNS: Found device huvanmusic Aug 16 23:46:50 lobbymusic volumio[1169]: Discovery: adding 31217c3d-1999-4c1d-ba60-14b03625b10f Aug 16 23:46:50 lobbymusic volumio[1169]: info: mDNS: Found device gallery Aug 16 23:46:51 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:51 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: adding b7d17b14-2289-4417-a5b5-53d266211bc9 Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device clubhousemusic Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: adding 20c576cf-ad87-4f79-a095-ea1d4d25d123 Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device orchidspamusic Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: adding e2b10d3f-2f81-4bff-a9fe-ecca7196c2fe Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device grillbarmusic Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: adding b3dff07d-1313-4730-97a4-ccc905193004 Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device sandbarmusic Aug 16 23:46:51 lobbymusic sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 16 23:46:51 lobbymusic sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 23:46:51 lobbymusic systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 16 23:46:51 lobbymusic systemd[1]: Started UPnP Renderer front-end to MPD. Aug 16 23:46:51 lobbymusic sudo[1414]: pam_unix(sudo:session): session closed for user root Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: adding b6c76c73-9206-404c-bd43-cf1f7157d5ba Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device umiyakimusic Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: adding 0f0cef22-37a4-4143-a632-905d23b0fc36 Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device lobbymusic Aug 16 23:46:51 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:51 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:51 lobbymusic volumio[1169]: Discovery: this is already registered, 0f0cef22-37a4-4143-a632-905d23b0fc36 Aug 16 23:46:51 lobbymusic volumio[1169]: info: mDNS: Found device lobbymusic Aug 16 23:46:51 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:46:51 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:46:51 lobbymusic volumio[1169]: info: Upmpdcli Daemon Started Aug 16 23:47:02 lobbymusic volumio[1169]: info: AutoStart - getting queue Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetQueue Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreStateMachine::getQueue Aug 16 23:47:02 lobbymusic volumio[1169]: info: CorePlayQueue::getQueue Aug 16 23:47:02 lobbymusic volumio[1169]: info: AutoStart - start playing -> queue is not empty Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPlay Aug 16 23:47:02 lobbymusic volumio[1169]: UNSET VOLATILE Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreStateMachine::play index undefined Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 16 23:47:02 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreStateMachine::startPlaybackTimer Aug 16 23:47:02 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:02 lobbymusic volumio[1169]: verbose: ControllerMpd::clearAddPlayTracks NAS/Lobby_NAS/Chillout lounge/48th St. Collective,Michelle Simonal - Over My Shoulder .mp3 Aug 16 23:47:02 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Aug 16 23:47:02 lobbymusic volumio[1169]: info: sendMpdCommand stop took 12 milliseconds Aug 16 23:47:02 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Aug 16 23:47:02 lobbymusic volumio[1169]: info: Aug 16 23:47:02 lobbymusic volumio[1169]: ---------------------------- MPD announces system playlist update Aug 16 23:47:02 lobbymusic volumio[1169]: info: Ignoring MPD Status Update Aug 16 23:47:02 lobbymusic volumio[1169]: info: sendMpdCommand clear took 9 milliseconds Aug 16 23:47:02 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand add "NAS/Lobby_NAS/Chillout lounge/48th St. Collective,Michelle Simonal - Over My Shoulder .mp3" Aug 16 23:47:02 lobbymusic volumio[1169]: error: updateQueue error: null Aug 16 23:47:02 lobbymusic volumio[1169]: info: Aug 16 23:47:02 lobbymusic volumio[1169]: ---------------------------- MPD announces system playlist update Aug 16 23:47:02 lobbymusic volumio[1169]: info: Ignoring MPD Status Update Aug 16 23:47:02 lobbymusic volumio[1169]: info: ------------------------------ 28ms Aug 16 23:47:02 lobbymusic volumio[1169]: info: sendMpdCommand add "NAS/Lobby_NAS/Chillout lounge/48th St. Collective,Michelle Simonal - Over My Shoulder .mp3" took 26 milliseconds Aug 16 23:47:02 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Aug 16 23:47:02 lobbymusic volumio[1169]: info: ------------------------------ 6ms Aug 16 23:47:02 lobbymusic volumio[1169]: info: sendMpdCommand play took 3 milliseconds Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetVisibleSources Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetState Aug 16 23:47:02 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioGetQueue Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreStateMachine::getQueue Aug 16 23:47:02 lobbymusic volumio[1169]: info: CorePlayQueue::getQueue Aug 16 23:47:02 lobbymusic volumio[1169]: info: Listing playlists Aug 16 23:47:02 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 16 23:47:03 lobbymusic volumio[1169]: info: Aug 16 23:47:03 lobbymusic volumio[1169]: ---------------------------- MPD announces state update: player Aug 16 23:47:03 lobbymusic volumio[1169]: info: ControllerMpd::getState Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Aug 16 23:47:03 lobbymusic volumio[1169]: info: Aug 16 23:47:03 lobbymusic volumio[1169]: ---------------------------- MPD announces state update: player Aug 16 23:47:03 lobbymusic volumio[1169]: info: ControllerMpd::getState Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Aug 16 23:47:03 lobbymusic volumio[1169]: info: sendMpdCommand status took 7 milliseconds Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::parseState Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 16 23:47:03 lobbymusic volumio[1169]: info: sendMpdCommand status took 15 milliseconds Aug 16 23:47:03 lobbymusic volumio[1169]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::parseState Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::parseTrackInfo Aug 16 23:47:03 lobbymusic volumio[1169]: info: ControllerMpd::pushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::servicePushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Over My Shoulder","artist":"48th St. Collective/Michelle Simonal","album":"Over My Shoulder","uri":"NAS/Lobby_NAS/Chillout lounge/48th St. Collective,Michelle Simonal - Over My Shoulder .mp3","trackType":"mp3"} Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: CURRENT POSITION 0 Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::syncState stateService play Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:47:03 lobbymusic volumio[1169]: info: ------------------------------ 56ms Aug 16 23:47:03 lobbymusic volumio[1169]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: ControllerMpd::parseTrackInfo Aug 16 23:47:03 lobbymusic volumio[1169]: info: ControllerMpd::pushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::servicePushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":221,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Over My Shoulder","artist":"48th St. Collective/Michelle Simonal","album":"Over My Shoulder","uri":"NAS/Lobby_NAS/Chillout lounge/48th St. Collective,Michelle Simonal - Over My Shoulder .mp3","trackType":"mp3"} Aug 16 23:47:03 lobbymusic volumio[1169]: verbose: CURRENT POSITION 0 Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::syncState stateService play Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::syncState currentStatus play Aug 16 23:47:03 lobbymusic volumio[1169]: info: Received an update from plugin. extracting info from payload Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreStateMachine::pushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CorePlayQueue::getTrack 0 Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::volumioPushState Aug 16 23:47:03 lobbymusic volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 23:47:03 lobbymusic volumio[1169]: info: ------------------------------ 83ms Aug 16 23:47:03 lobbymusic volumio[1169]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 23:47:03 lobbymusic volumio[1169]: { Error: certificate is not yet valid Aug 16 23:47:03 lobbymusic volumio[1169]: at TLSSocket. (_tls_wrap.js:1105:38) Aug 16 23:47:03 lobbymusic volumio[1169]: at emitNone (events.js:106:13) Aug 16 23:47:03 lobbymusic volumio[1169]: at TLSSocket.emit (events.js:208:7) Aug 16 23:47:03 lobbymusic volumio[1169]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Aug 16 23:47:03 lobbymusic volumio[1169]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Aug 16 23:47:03 lobbymusic volumio[1169]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 23:47:04 lobbymusic sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-08-16 23:46 Aug 16 23:47:04 lobbymusic sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"