-- Logs begin at Wed 2024-05-01 23:34:08 UTC, end at Wed 2024-05-01 23:35:17 UTC. -- May 01 23:34:08 omvolume systemd-journal[168]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.7M free of 480.6M available → current limit 30.0M). May 01 23:34:08 omvolume kernel: Booting Linux on physical CPU 0xf00 May 01 23:34:08 omvolume kernel: Linux version 4.19.86-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1283 SMP Fri Nov 29 18:32:25 GMT 2019 May 01 23:34:08 omvolume kernel: CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d May 01 23:34:08 omvolume kernel: CPU: div instructions available: patching division code May 01 23:34:08 omvolume kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache May 01 23:34:08 omvolume kernel: OF: fdt: Machine model: Raspberry Pi 2 Model B Rev 1.1 May 01 23:34:08 omvolume kernel: Memory policy: Data cache writealloc May 01 23:34:08 omvolume kernel: cma: Reserved 8 MiB at 0x3d800000 May 01 23:34:08 omvolume kernel: On node 0 totalpages: 253952 May 01 23:34:08 omvolume kernel: Normal zone: 2232 pages used for memmap May 01 23:34:08 omvolume kernel: Normal zone: 0 pages reserved May 01 23:34:08 omvolume kernel: Normal zone: 253952 pages, LIFO batch:63 May 01 23:34:08 omvolume kernel: random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0 May 01 23:34:08 omvolume kernel: percpu: Embedded 16 pages/cpu s36864 r8192 d20480 u65536 May 01 23:34:08 omvolume kernel: pcpu-alloc: s36864 r8192 d20480 u65536 alloc=16*4096 May 01 23:34:08 omvolume kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 01 23:34:08 omvolume kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 May 01 23:34:08 omvolume kernel: Kernel command line: coherent_pool=1M 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=ttyAMA0,115200 kgdboc=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 May 01 23:34:08 omvolume kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) May 01 23:34:08 omvolume kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) May 01 23:34:08 omvolume kernel: Memory: 964288K/1015808K available (8192K kernel code, 653K rwdata, 2220K rodata, 1024K init, 822K bss, 43328K reserved, 8192K cma-reserved) May 01 23:34:08 omvolume 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) ( 654 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 823 kB) May 01 23:34:08 omvolume kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 01 23:34:08 omvolume kernel: ftrace: allocating 26847 entries in 79 pages May 01 23:34:08 omvolume kernel: rcu: Hierarchical RCU implementation. May 01 23:34:08 omvolume kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 May 01 23:34:08 omvolume kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). May 01 23:34:08 omvolume kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns May 01 23:34:08 omvolume kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns May 01 23:34:08 omvolume kernel: Switching to timer-based delay loop, resolution 52ns May 01 23:34:08 omvolume kernel: Console: colour dummy device 80x30 May 01 23:34:08 omvolume kernel: console [tty1] enabled May 01 23:34:08 omvolume kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) May 01 23:34:08 omvolume kernel: pid_max: default: 32768 minimum: 301 May 01 23:34:08 omvolume kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) May 01 23:34:08 omvolume kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) May 01 23:34:08 omvolume kernel: CPU: Testing write buffer coherency: ok May 01 23:34:08 omvolume kernel: CPU0: update cpu_capacity 1024 May 01 23:34:08 omvolume kernel: CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00 May 01 23:34:08 omvolume kernel: Setting up static identity map for 0x100000 - 0x10003c May 01 23:34:08 omvolume kernel: rcu: Hierarchical SRCU implementation. May 01 23:34:08 omvolume kernel: smp: Bringing up secondary CPUs ... May 01 23:34:08 omvolume kernel: CPU1: update cpu_capacity 1024 May 01 23:34:08 omvolume kernel: CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01 May 01 23:34:08 omvolume kernel: CPU2: update cpu_capacity 1024 May 01 23:34:08 omvolume kernel: CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02 May 01 23:34:08 omvolume kernel: CPU3: update cpu_capacity 1024 May 01 23:34:08 omvolume kernel: CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03 May 01 23:34:08 omvolume kernel: smp: Brought up 1 node, 4 CPUs May 01 23:34:08 omvolume kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). May 01 23:34:08 omvolume kernel: CPU: All CPU(s) started in HYP mode. May 01 23:34:08 omvolume kernel: CPU: Virtualization extensions available. May 01 23:34:08 omvolume kernel: devtmpfs: initialized May 01 23:34:08 omvolume kernel: VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 May 01 23:34:08 omvolume kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns May 01 23:34:08 omvolume kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) May 01 23:34:08 omvolume kernel: pinctrl core: initialized pinctrl subsystem May 01 23:34:08 omvolume kernel: NET: Registered protocol family 16 May 01 23:34:08 omvolume kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations May 01 23:34:08 omvolume kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. May 01 23:34:08 omvolume kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. May 01 23:34:08 omvolume kernel: Serial: AMBA PL011 UART driver May 01 23:34:08 omvolume kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled May 01 23:34:08 omvolume kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 May 01 23:34:08 omvolume kernel: SCSI subsystem initialized May 01 23:34:08 omvolume kernel: usbcore: registered new interface driver usbfs May 01 23:34:08 omvolume kernel: usbcore: registered new interface driver hub May 01 23:34:08 omvolume kernel: usbcore: registered new device driver usb May 01 23:34:08 omvolume kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-11-29 18:46, variant start May 01 23:34:08 omvolume kernel: raspberrypi-firmware soc:firmware: Firmware hash is 7d5bdbd77968098c981dadefae06aaec79354c69 May 01 23:34:08 omvolume kernel: clocksource: Switched to clocksource arch_sys_counter May 01 23:34:08 omvolume kernel: VFS: Disk quotas dquot_6.6.0 May 01 23:34:08 omvolume kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) May 01 23:34:08 omvolume kernel: FS-Cache: Loaded May 01 23:34:08 omvolume kernel: CacheFiles: Loaded May 01 23:34:08 omvolume kernel: NET: Registered protocol family 2 May 01 23:34:08 omvolume kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) May 01 23:34:08 omvolume kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) May 01 23:34:08 omvolume kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) May 01 23:34:08 omvolume kernel: TCP: Hash tables configured (established 8192 bind 8192) May 01 23:34:08 omvolume kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) May 01 23:34:08 omvolume kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) May 01 23:34:08 omvolume kernel: NET: Registered protocol family 1 May 01 23:34:08 omvolume kernel: RPC: Registered named UNIX socket transport module. May 01 23:34:08 omvolume kernel: RPC: Registered udp transport module. May 01 23:34:08 omvolume kernel: RPC: Registered tcp transport module. May 01 23:34:08 omvolume kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 01 23:34:08 omvolume kernel: Trying to unpack rootfs image as initramfs... May 01 23:34:08 omvolume kernel: Freeing initrd memory: 19456K May 01 23:34:08 omvolume kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available May 01 23:34:08 omvolume kernel: Initialise system trusted keyrings May 01 23:34:08 omvolume kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 May 01 23:34:08 omvolume kernel: FS-Cache: Netfs 'nfs' registered for caching May 01 23:34:08 omvolume kernel: NFS: Registering the id_resolver key type May 01 23:34:08 omvolume kernel: Key type id_resolver registered May 01 23:34:08 omvolume kernel: Key type id_legacy registered May 01 23:34:08 omvolume kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... May 01 23:34:08 omvolume kernel: Key type asymmetric registered May 01 23:34:08 omvolume kernel: Asymmetric key parser 'x509' registered May 01 23:34:08 omvolume kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 01 23:34:08 omvolume kernel: io scheduler noop registered (default) May 01 23:34:08 omvolume kernel: io scheduler deadline registered May 01 23:34:08 omvolume kernel: io scheduler cfq registered May 01 23:34:08 omvolume kernel: io scheduler mq-deadline registered May 01 23:34:08 omvolume kernel: io scheduler kyber registered May 01 23:34:08 omvolume kernel: bcm2708_fb soc:fb: FB found 1 display(s) May 01 23:34:08 omvolume kernel: Console: switching to colour frame buffer device 80x30 May 01 23:34:08 omvolume kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 May 01 23:34:08 omvolume kernel: bcm2835-rng 3f104000.rng: hwrng registered May 01 23:34:08 omvolume kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) May 01 23:34:08 omvolume kernel: vc-sm: Videocore shared memory driver May 01 23:34:08 omvolume kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 May 01 23:34:08 omvolume kernel: brd: module loaded May 01 23:34:08 omvolume kernel: loop: module loaded May 01 23:34:08 omvolume kernel: Loading iSCSI transport class v2.0-870. May 01 23:34:08 omvolume kernel: libphy: Fixed MDIO Bus: probed May 01 23:34:08 omvolume kernel: usbcore: registered new interface driver lan78xx May 01 23:34:08 omvolume kernel: usbcore: registered new interface driver smsc95xx May 01 23:34:08 omvolume kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) May 01 23:34:08 omvolume kernel: dwc_otg 3f980000.usb: base=(ptrval) May 01 23:34:08 omvolume kernel: Core Release: 2.80a May 01 23:34:08 omvolume kernel: Setting default values for core params May 01 23:34:08 omvolume kernel: Finished setting default values for core params May 01 23:34:08 omvolume kernel: Using Buffer DMA mode May 01 23:34:08 omvolume kernel: Periodic Transfer Interrupt Enhancement - disabled May 01 23:34:08 omvolume kernel: Multiprocessor Interrupt Enhancement - disabled May 01 23:34:08 omvolume kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 May 01 23:34:08 omvolume kernel: Dedicated Tx FIFOs mode May 01 23:34:08 omvolume kernel: WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = bd914000 dma = 0xfd914000 len=9024 May 01 23:34:08 omvolume kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled May 01 23:34:08 omvolume kernel: dwc_otg: Microframe scheduler enabled May 01 23:34:08 omvolume kernel: WARN::hcd_init_fiq:457: FIQ on core 1 May 01 23:34:08 omvolume kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8067e550 length 36 May 01 23:34:08 omvolume kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 May 01 23:34:08 omvolume kernel: dwc_otg 3f980000.usb: DWC OTG Controller May 01 23:34:08 omvolume kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 May 01 23:34:08 omvolume kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 May 01 23:34:08 omvolume kernel: Init: Port Power? op_state=1 May 01 23:34:08 omvolume kernel: Init: Power Port (0) May 01 23:34:08 omvolume kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 May 01 23:34:08 omvolume kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 01 23:34:08 omvolume kernel: usb usb1: Product: DWC OTG Controller May 01 23:34:08 omvolume kernel: usb usb1: Manufacturer: Linux 4.19.86-v7+ dwc_otg_hcd May 01 23:34:08 omvolume kernel: usb usb1: SerialNumber: 3f980000.usb May 01 23:34:08 omvolume kernel: hub 1-0:1.0: USB hub found May 01 23:34:08 omvolume kernel: hub 1-0:1.0: 1 port detected May 01 23:34:08 omvolume kernel: dwc_otg: FIQ enabled May 01 23:34:08 omvolume kernel: dwc_otg: NAK holdoff enabled May 01 23:34:08 omvolume kernel: dwc_otg: FIQ split-transaction FSM enabled May 01 23:34:08 omvolume kernel: Module dwc_common_port init May 01 23:34:08 omvolume kernel: usbcore: registered new interface driver usb-storage May 01 23:34:08 omvolume kernel: mousedev: PS/2 mouse device common for all mice May 01 23:34:08 omvolume kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer May 01 23:34:08 omvolume kernel: bcm2835-cpufreq: min=600000 max=900000 May 01 23:34:08 omvolume kernel: sdhci: Secure Digital Host Controller Interface driver May 01 23:34:08 omvolume kernel: sdhci: Copyright(c) Pierre Ossman May 01 23:34:08 omvolume kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe May 01 23:34:08 omvolume kernel: sdhci-pltfm: SDHCI platform and OF driver helper May 01 23:34:08 omvolume kernel: ledtrig-cpu: registered to indicate activity on CPUs May 01 23:34:08 omvolume kernel: hidraw: raw HID events driver (C) Jiri Kosina May 01 23:34:08 omvolume kernel: usbcore: registered new interface driver usbhid May 01 23:34:08 omvolume kernel: usbhid: USB HID core driver May 01 23:34:08 omvolume kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 May 01 23:34:08 omvolume kernel: [vc_sm_connected_init]: start May 01 23:34:08 omvolume kernel: [vc_sm_connected_init]: end - returning 0 May 01 23:34:08 omvolume kernel: Initializing XFRM netlink socket May 01 23:34:08 omvolume kernel: NET: Registered protocol family 17 May 01 23:34:08 omvolume kernel: Key type dns_resolver registered May 01 23:34:08 omvolume kernel: Registering SWP/SWPB emulation handler May 01 23:34:08 omvolume kernel: registered taskstats version 1 May 01 23:34:08 omvolume kernel: Loading compiled-in X.509 certificates May 01 23:34:08 omvolume kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled May 01 23:34:08 omvolume kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 May 01 23:34:08 omvolume kernel: console [ttyAMA0] enabled May 01 23:34:08 omvolume kernel: sdhost: log_buf @ (ptrval) (fd913000) May 01 23:34:08 omvolume kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) May 01 23:34:08 omvolume kernel: of_cfs_init May 01 23:34:08 omvolume kernel: of_cfs_init: OK May 01 23:34:08 omvolume kernel: Freeing unused kernel memory: 1024K May 01 23:34:08 omvolume kernel: Run /init as init process May 01 23:34:08 omvolume kernel: mmc0: host does not support reading read-only switch, assuming write-enable May 01 23:34:08 omvolume kernel: mmc0: new high speed SDHC card at address aaaa May 01 23:34:08 omvolume kernel: mmcblk0: mmc0:aaaa SL16G 14.8 GiB May 01 23:34:08 omvolume kernel: mmcblk0: p1 p2 p3 May 01 23:34:08 omvolume kernel: Indeed it is in host mode hprt0 = 00021501 May 01 23:34:08 omvolume kernel: random: fast init done May 01 23:34:08 omvolume initramfs: Booting Volumio for BCM2835 May 01 23:34:08 omvolume initramfs: This script mounts rootfs RO with an overlay RW layer. May 01 23:34:08 omvolume kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 01 23:34:08 omvolume kernel: fuse init (API version 7.27) May 01 23:34:08 omvolume initramfs: IMGPART=/dev/mmcblk0p2 May 01 23:34:08 omvolume initramfs: IMGFILE=/volumio_current.sqsh May 01 23:34:08 omvolume initramfs: Boot delay (except first time) will be 5 seconds May 01 23:34:08 omvolume kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg May 01 23:34:08 omvolume kernel: Indeed it is in host mode hprt0 = 00001101 May 01 23:34:08 omvolume initramfs: /dev/mmcblk0p2: May 01 23:34:08 omvolume kernel: EXT4-fs (mmcblk0p2): recovery complete May 01 23:34:08 omvolume kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) May 01 23:34:08 omvolume initramfs: Doing a 5 second delay here to give kernel load a headstart May 01 23:34:08 omvolume kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 May 01 23:34:08 omvolume kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 01 23:34:08 omvolume kernel: hub 1-1:1.0: USB hub found May 01 23:34:08 omvolume kernel: hub 1-1:1.0: 5 ports detected May 01 23:34:08 omvolume kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg May 01 23:34:08 omvolume kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 May 01 23:34:08 omvolume kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 01 23:34:08 omvolume kernel: smsc95xx v1.0.6 May 01 23:34:08 omvolume kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:25:f8:7f May 01 23:34:08 omvolume initramfs: Checking for USB updates May 01 23:34:08 omvolume initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) May 01 23:34:08 omvolume kernel: EXT4-fs (mmcblk0p3): recovery complete May 01 23:34:08 omvolume kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) May 01 23:34:08 omvolume initramfs: With Option: May 01 23:34:08 omvolume initramfs: VOLUMIO_VERSION="2.698" May 01 23:34:08 omvolume initramfs: Finish initramfs, continue booting Volumio May 01 23:34:08 omvolume kernel: random: systemd: uninitialized urandom read (16 bytes read) May 01 23:34:08 omvolume systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) May 01 23:34:08 omvolume systemd[1]: Detected architecture 'arm'. May 01 23:34:08 omvolume kernel: NET: Registered protocol family 10 May 01 23:34:08 omvolume kernel: Segment Routing with IPv6 May 01 23:34:08 omvolume systemd[1]: Inserted module 'ipv6' May 01 23:34:08 omvolume systemd[1]: Set hostname to . May 01 23:34:08 omvolume kernel: uart-pl011 3f201000.serial: no DMA platform data May 01 23:34:08 omvolume kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) May 01 23:34:08 omvolume kernel: random: systemd: uninitialized urandom read (16 bytes read) May 01 23:34:08 omvolume systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' May 01 23:34:08 omvolume systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' May 01 23:34:08 omvolume systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' May 01 23:34:08 omvolume systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' May 01 23:34:08 omvolume systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. May 01 23:34:08 omvolume systemd[1]: Starting Forward Password Requests to Wall Directory Watch. May 01 23:34:08 omvolume kernel: i2c /dev entries driver May 01 23:34:08 omvolume systemd-udevd[177]: starting version 215 May 01 23:34:08 omvolume systemd-journal[168]: Journal started May 01 23:34:08 omvolume systemd[1]: Starting Slices. May 01 23:34:08 omvolume systemd[1]: Reached target Slices. May 01 23:34:08 omvolume systemd[1]: Starting Remount Root and Kernel File Systems... May 01 23:34:08 omvolume systemd[1]: Started Create list of required static device nodes for the current kernel. May 01 23:34:08 omvolume systemd[1]: Starting Create Static Device Nodes in /dev... May 01 23:34:08 omvolume systemd[1]: Time has been changed May 01 23:34:08 omvolume systemd[1]: Started Restore / save the current clock. May 01 23:34:08 omvolume systemd[1]: Started Create Static Device Nodes in /dev. May 01 23:34:08 omvolume systemd[1]: Starting udev Kernel Device Manager... May 01 23:34:08 omvolume systemd-modules-load[166]: Inserted module 'i2c_dev' May 01 23:34:08 omvolume systemd[1]: Started Load Kernel Modules. May 01 23:34:08 omvolume systemd[1]: Started udev Coldplug all Devices. May 01 23:34:08 omvolume fake-hwclock[157]: Wed May 1 23:34:08 UTC 2024 May 01 23:34:08 omvolume systemd[1]: Started Various fixups to make systemd work better on Debian. May 01 23:34:08 omvolume systemd[1]: Starting Load/Save Random Seed... May 01 23:34:08 omvolume systemd[1]: Starting Local File Systems (Pre). May 01 23:34:08 omvolume systemd[1]: Reached target Local File Systems (Pre). May 01 23:34:08 omvolume systemd[1]: Mounting /tmp... May 01 23:34:08 omvolume systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 01 23:34:08 omvolume systemd[1]: Mounting /var/spool/cups... May 01 23:34:08 omvolume systemd[1]: Mounting /var/log... May 01 23:34:08 omvolume systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 01 23:34:08 omvolume systemd[1]: Starting Show Plymouth Boot Screen... May 01 23:34:08 omvolume systemd[1]: Starting Copy rules generated while the root was ro... May 01 23:34:08 omvolume systemd[1]: Mounted /var/log. May 01 23:34:08 omvolume systemd[1]: Mounted /var/spool/cups. May 01 23:34:08 omvolume systemd[1]: Mounted /tmp. May 01 23:34:08 omvolume systemd[1]: Started Load/Save Random Seed. May 01 23:34:08 omvolume kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 01 23:34:08 omvolume systemd[1]: Started Copy rules generated while the root was ro. May 01 23:34:08 omvolume kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 01 23:34:08 omvolume kernel: [vc_sm_connected_init]: start May 01 23:34:08 omvolume kernel: [vc_sm_connected_init]: installed successfully May 01 23:34:08 omvolume kernel: media: Linux media interface: v0.10 May 01 23:34:08 omvolume systemd[1]: Started Show Plymouth Boot Screen. May 01 23:34:08 omvolume kernel: videodev: Linux video capture interface: v2.00 May 01 23:34:08 omvolume systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. May 01 23:34:08 omvolume systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 01 23:34:08 omvolume systemd[1]: Started Dispatch Password Requests to Console Directory Watch. May 01 23:34:08 omvolume systemd[1]: Starting Paths. May 01 23:34:08 omvolume systemd[1]: Reached target Paths. May 01 23:34:08 omvolume systemd[1]: Mounting /var/spool/cups/tmp... May 01 23:34:09 omvolume systemd[1]: Mounted /var/spool/cups/tmp. May 01 23:34:09 omvolume kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 01 23:34:09 omvolume kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 01 23:34:09 omvolume systemd[1]: Found device /dev/ttyAMA0. May 01 23:34:09 omvolume kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 01 23:34:09 omvolume kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 01 23:34:09 omvolume kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 01 23:34:09 omvolume kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 01 23:34:09 omvolume kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 01 23:34:09 omvolume kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 01 23:34:09 omvolume kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 01 23:34:09 omvolume kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 01 23:34:09 omvolume kernel: snd_bcm2835: unknown parameter 'index' ignored May 01 23:34:09 omvolume kernel: bcm2835_audio soc:audio: card created with 8 channels May 01 23:34:09 omvolume systemd[1]: Starting Sound Card. May 01 23:34:09 omvolume systemd[1]: Reached target Sound Card. May 01 23:34:09 omvolume systemd[1]: Found device /dev/mmcblk0p1. May 01 23:34:09 omvolume systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 01 23:34:09 omvolume systemd[1]: Starting system-ifup.slice. May 01 23:34:09 omvolume systemd[1]: Created slice system-ifup.slice. May 01 23:34:10 omvolume systemd-fsck[282]: fsck.fat 3.0.27 (2014-11-12) May 01 23:34:10 omvolume systemd-fsck[282]: /dev/mmcblk0p1: 227 files, 119241/123044 clusters May 01 23:34:10 omvolume systemd[1]: Started File System Check on /dev/mmcblk0p1. May 01 23:34:10 omvolume systemd[1]: Mounting /boot... May 01 23:34:10 omvolume systemd[1]: Mounted /boot. May 01 23:34:10 omvolume systemd[1]: Starting Local File Systems. May 01 23:34:10 omvolume systemd[1]: Reached target Local File Systems. May 01 23:34:10 omvolume systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 01 23:34:10 omvolume systemd[1]: Starting Remote File Systems. May 01 23:34:10 omvolume systemd[1]: Reached target Remote File Systems. May 01 23:34:10 omvolume systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... May 01 23:34:10 omvolume systemd[1]: Starting Create Volatile Files and Directories... May 01 23:34:10 omvolume systemd[1]: Starting LSB: Raise network interfaces.... May 01 23:34:10 omvolume systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 01 23:34:10 omvolume systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. May 01 23:34:10 omvolume systemd[1]: Started Create Volatile Files and Directories. May 01 23:34:10 omvolume systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 01 23:34:10 omvolume systemd[1]: Started Update UTMP about System Boot/Shutdown. May 01 23:34:10 omvolume kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 01 23:34:10 omvolume kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 01 23:34:10 omvolume kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 May 01 23:34:10 omvolume kernel: cfg80211: failed to load regulatory.db May 01 23:34:10 omvolume networking[295]: Configuring network interfaces...command failed: No such device (-19) May 01 23:34:11 omvolume networking[295]: command failed: No such device (-19) May 01 23:34:11 omvolume systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd). May 01 23:34:11 omvolume networking[295]: command failed: No such device (-19) May 01 23:34:12 omvolume kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 01 23:34:12 omvolume networking[295]: command failed: No such device (-19) May 01 23:34:12 omvolume networking[295]: dhcpcd[453]: version 6.0.5 starting May 01 23:34:12 omvolume dhcpcd[453]: version 6.0.5 starting May 01 23:34:12 omvolume dhcpcd[453]: eth0: soliciting an IPv6 router May 01 23:34:12 omvolume networking[295]: dhcpcd[453]: eth0: soliciting an IPv6 router May 01 23:34:12 omvolume dhcpcd[453]: eth0: soliciting a DHCP lease May 01 23:34:12 omvolume networking[295]: dhcpcd[453]: eth0: soliciting a DHCP lease May 01 23:34:13 omvolume dhcpcd[453]: eth0: carrier lost May 01 23:34:13 omvolume networking[295]: dhcpcd[453]: eth0: carrier lost May 01 23:34:13 omvolume kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x45E1 May 01 23:34:13 omvolume dhcpcd[453]: eth0: carrier acquired May 01 23:34:13 omvolume networking[295]: dhcpcd[453]: eth0: carrier acquired May 01 23:34:13 omvolume dhcpcd[453]: eth0: soliciting an IPv6 router May 01 23:34:13 omvolume networking[295]: dhcpcd[453]: eth0: soliciting an IPv6 router May 01 23:34:14 omvolume dhcpcd[453]: eth0: soliciting a DHCP lease May 01 23:34:14 omvolume networking[295]: dhcpcd[453]: eth0: soliciting a DHCP lease May 01 23:34:15 omvolume dhcpcd[453]: eth0: offered 192.168.1.187 from 192.168.1.254 May 01 23:34:15 omvolume networking[295]: dhcpcd[453]: eth0: offered 192.168.1.187 from 192.168.1.254 May 01 23:34:20 omvolume dhcpcd[453]: eth0: leased 192.168.1.187 for 86400 seconds May 01 23:34:20 omvolume dhcpcd[453]: eth0: adding host route to 192.168.1.187 via 127.0.0.1 May 01 23:34:20 omvolume dhcpcd[453]: eth0: adding route to 192.168.1.0/24 May 01 23:34:20 omvolume dhcpcd[453]: eth0: adding default route via 192.168.1.254 May 01 23:34:20 omvolume networking[295]: dhcpcd[453]: eth0: leased 192.168.1.187 for 86400 seconds May 01 23:34:20 omvolume networking[295]: dhcpcd[453]: eth0: adding host route to 192.168.1.187 via 127.0.0.1 May 01 23:34:20 omvolume networking[295]: dhcpcd[453]: eth0: adding route to 192.168.1.0/24 May 01 23:34:20 omvolume networking[295]: dhcpcd[453]: eth0: adding default route via 192.168.1.254 May 01 23:34:20 omvolume dhcpcd[453]: forked to background, child pid 511 May 01 23:34:20 omvolume networking[295]: dhcpcd[453]: forked to background, child pid 511 May 01 23:34:21 omvolume networking[295]: done. May 01 23:34:21 omvolume systemd[1]: Started LSB: Raise network interfaces.. May 01 23:34:21 omvolume systemd[1]: Starting ifup for eth0... May 01 23:34:21 omvolume systemd[1]: Started ifup for eth0. May 01 23:34:21 omvolume systemd[1]: Starting Network. May 01 23:34:21 omvolume systemd[1]: Reached target Network. May 01 23:34:21 omvolume systemd[1]: Starting Network is Online. May 01 23:34:21 omvolume systemd[1]: Reached target Network is Online. May 01 23:34:21 omvolume systemd[1]: Starting LSB: RPC portmapper replacement... May 01 23:34:21 omvolume ifup[557]: /sbin/ifup: interface eth0 already configured May 01 23:34:21 omvolume rpcbind[558]: Starting rpcbind daemon.... May 01 23:34:21 omvolume systemd[1]: Started LSB: RPC portmapper replacement. May 01 23:34:21 omvolume systemd[1]: Starting RPC Port Mapper. May 01 23:34:21 omvolume systemd[1]: Reached target RPC Port Mapper. May 01 23:34:21 omvolume systemd[1]: Starting LSB: NFS support files common to client and server... May 01 23:34:21 omvolume rpc.statd[580]: Version 1.2.8 starting May 01 23:34:21 omvolume sm-notify[581]: Version 1.2.8 starting May 01 23:34:21 omvolume kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). May 01 23:34:22 omvolume nfs-common[574]: Starting NFS common utilities: statd idmapd. May 01 23:34:22 omvolume systemd[1]: Started LSB: NFS support files common to client and server. May 01 23:34:22 omvolume systemd[1]: Starting System Initialization. May 01 23:34:22 omvolume systemd[1]: Reached target System Initialization. May 01 23:34:22 omvolume systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. May 01 23:34:22 omvolume systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 01 23:34:22 omvolume systemd[1]: Starting mpd.socket. May 01 23:34:22 omvolume systemd[1]: Listening on mpd.socket. May 01 23:34:22 omvolume systemd[1]: Starting D-Bus System Message Bus Socket. May 01 23:34:22 omvolume systemd[1]: Listening on D-Bus System Message Bus Socket. May 01 23:34:22 omvolume systemd[1]: Starting Sockets. May 01 23:34:22 omvolume systemd[1]: Reached target Sockets. May 01 23:34:22 omvolume systemd[1]: Starting Daily Cleanup of Temporary Directories. May 01 23:34:22 omvolume systemd[1]: Started Daily Cleanup of Temporary Directories. May 01 23:34:22 omvolume systemd[1]: Starting Timers. May 01 23:34:22 omvolume systemd[1]: Reached target Timers. May 01 23:34:22 omvolume systemd[1]: Started Manage Sound Card State (restore and store). May 01 23:34:22 omvolume systemd[1]: Starting Restore Sound Card State... May 01 23:34:22 omvolume systemd[1]: Starting Basic System. May 01 23:34:22 omvolume systemd[1]: Reached target Basic System. May 01 23:34:22 omvolume systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... May 01 23:34:22 omvolume systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 01 23:34:22 omvolume systemd[1]: Starting cd.service... May 01 23:34:22 omvolume systemd[1]: Started cd.service. May 01 23:34:22 omvolume systemd[1]: Started Configure Bluetooth Modems connected by UART. May 01 23:34:22 omvolume systemd[1]: Starting Volumio Iptables Module... May 01 23:34:22 omvolume systemd[1]: Started Volumio Iptables Module. May 01 23:34:22 omvolume systemd[1]: Starting mount disks automatically with standby... May 01 23:34:22 omvolume systemd[1]: Started mount disks automatically with standby. May 01 23:34:22 omvolume systemd[1]: Starting UPnP Renderer front-end to MPD... May 01 23:34:22 omvolume systemd[1]: Started UPnP Renderer front-end to MPD. May 01 23:34:22 omvolume systemd[1]: Starting volumio-remote-updater.service... May 01 23:34:22 omvolume systemd[1]: Started volumio-remote-updater.service. May 01 23:34:22 omvolume systemd[1]: Starting Volumio SSH enabler... May 01 23:34:22 omvolume systemd[1]: Started Volumio SSH enabler. May 01 23:34:22 omvolume systemd[1]: Starting Wireless Services... May 01 23:34:22 omvolume systemd[1]: Starting Xbindrc... May 01 23:34:22 omvolume systemd[1]: Started Xbindrc. May 01 23:34:22 omvolume systemd[1]: Starting /etc/rc.local Compatibility... May 01 23:34:22 omvolume volumio[599]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 01 23:34:22 omvolume systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. May 01 23:34:22 omvolume systemd[1]: Starting Login Service... May 01 23:34:22 omvolume systemd[1]: Starting LSB: start Winbind daemon... May 01 23:34:22 omvolume systemd[1]: Starting LSB: start Samba daemons for the AD DC... May 01 23:34:22 omvolume systemd[1]: Starting LSB: Start NTP daemon... May 01 23:34:22 omvolume systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... May 01 23:34:22 omvolume systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 01 23:34:22 omvolume systemd[1]: Starting LSB: Brings up/down network automatically... May 01 23:34:22 omvolume systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... May 01 23:34:22 omvolume systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... May 01 23:34:22 omvolume systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 01 23:34:22 omvolume systemd[1]: Starting Music Player Daemon... May 01 23:34:22 omvolume systemd[1]: Starting D-Bus System Message Bus... May 01 23:34:22 omvolume systemd[1]: Started D-Bus System Message Bus. May 01 23:34:22 omvolume dhcpcd[636]: Not running dhcpcd because there is aleady an interface specific instance ... failed! May 01 23:34:22 omvolume dhcpcd[636]: /var/run/dhcpcd-eth0.pid ... failed! May 01 23:34:22 omvolume ifplugd(eth0)[675]: ifplugd 0.28 initializing. May 01 23:34:22 omvolume ifplugd(eth0)[675]: Using interface eth0/B8:27:EB:25:F8:7F with driver (version: 22-Aug-2005) May 01 23:34:22 omvolume ifplugd(eth0)[675]: Using detection mode: SIOCETHTOOL May 01 23:34:22 omvolume ifplugd(eth0)[675]: Initialization complete, link beat detected. May 01 23:34:22 omvolume ifplugd(eth0)[675]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 01 23:34:22 omvolume loadcpufreq[622]: Loading cpufreq kernel modules...done (none). May 01 23:34:22 omvolume ifplugd(eth0)[675]: client: /sbin/ifup: interface eth0 already configured May 01 23:34:23 omvolume ifplugd(eth0)[675]: Program executed successfully. May 01 23:34:23 omvolume ifplugd[624]: Network Interface Plugging Daemon...start eth0...done. May 01 23:34:23 omvolume avahi-daemon[639]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). May 01 23:34:23 omvolume avahi-daemon[639]: Successfully dropped root privileges. May 01 23:34:23 omvolume avahi-daemon[639]: avahi-daemon 0.6.31 starting up. May 01 23:34:23 omvolume ntpd[677]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) May 01 23:34:23 omvolume ntp[617]: Starting NTP server: ntpd. May 01 23:34:23 omvolume ntpd[704]: proto: precision = 1.197 usec May 01 23:34:23 omvolume ntpd[704]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 May 01 23:34:23 omvolume ntpd[704]: Listen and drop on 1 v6wildcard :: UDP 123 May 01 23:34:23 omvolume ntpd[704]: Listen normally on 2 lo 127.0.0.1 UDP 123 May 01 23:34:23 omvolume ntpd[704]: Listen normally on 3 eth0 192.168.1.187 UDP 123 May 01 23:34:23 omvolume ntpd[704]: peers refreshed May 01 23:34:23 omvolume ntpd[704]: Listening on routing socket on fd #20 for interface updates May 01 23:34:23 omvolume sudo[609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off May 01 23:34:23 omvolume sudo[609]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 23:34:23 omvolume wifistart.sh[606]: command failed: No such device (-19) May 01 23:34:23 omvolume sudo[609]: pam_unix(sudo:session): session closed for user root May 01 23:34:23 omvolume avahi-daemon[639]: Successfully called chroot(). May 01 23:34:23 omvolume avahi-daemon[639]: Successfully dropped remaining capabilities. May 01 23:34:23 omvolume dbus[642]: [system] Successfully activated service 'org.freedesktop.systemd1' May 01 23:34:23 omvolume avahi-daemon[639]: Loading service file /services/volumio.service. May 01 23:34:23 omvolume systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 01 23:34:23 omvolume systemd[1]: Starting Permit User Sessions... May 01 23:34:23 omvolume systemd[1]: Started Restore Sound Card State. May 01 23:34:23 omvolume systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 01 23:34:23 omvolume systemd[1]: Unit cd.service entered failed state. May 01 23:34:23 omvolume avahi-daemon[639]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.187. May 01 23:34:23 omvolume avahi-daemon[639]: New relevant interface eth0.IPv4 for mDNS. May 01 23:34:23 omvolume avahi-daemon[639]: Network interface enumeration completed. May 01 23:34:23 omvolume avahi-daemon[639]: Registering new address record for 192.168.1.187 on eth0.IPv4. May 01 23:34:23 omvolume avahi-daemon[639]: Registering HINFO record with values 'ARMV7L'/'LINUX'. May 01 23:34:23 omvolume systemd[1]: Started /etc/rc.local Compatibility. May 01 23:34:23 omvolume systemd[1]: Started LSB: Start NTP daemon. May 01 23:34:23 omvolume systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 01 23:34:23 omvolume systemd[1]: Started LSB: Brings up/down network automatically. May 01 23:34:23 omvolume systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. May 01 23:34:23 omvolume systemd[1]: dhcpcd.service: control process exited, code=exited status=6 May 01 23:34:23 omvolume systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. May 01 23:34:23 omvolume systemd[1]: Unit dhcpcd.service entered failed state. May 01 23:34:23 omvolume systemd[1]: Started Permit User Sessions. May 01 23:34:23 omvolume dbus[642]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' May 01 23:34:23 omvolume systemd[1]: cd.service holdoff time over, scheduling restart. May 01 23:34:24 omvolume systemd-logind[612]: New seat seat0. May 01 23:34:24 omvolume systemd[1]: Started Login Service. May 01 23:34:24 omvolume systemd[1]: Starting Disk Manager (legacy version)... May 01 23:34:24 omvolume systemd[1]: Stopping cd.service... May 01 23:34:24 omvolume systemd[1]: Starting cd.service... May 01 23:34:24 omvolume systemd[1]: Started cd.service. May 01 23:34:24 omvolume systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 01 23:34:24 omvolume systemd[1]: Starting Hold until boot process finishes up... May 01 23:34:24 omvolume volumio[729]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 01 23:34:24 omvolume systemd[1]: Starting Terminate Plymouth Boot Screen... May 01 23:34:24 omvolume systemd[1]: Received SIGRTMIN+21 from PID 232 (plymouthd). May 01 23:34:24 omvolume systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 01 23:34:24 omvolume systemd[1]: Unit cd.service entered failed state. May 01 23:34:24 omvolume systemd[1]: Started Hold until boot process finishes up. May 01 23:34:24 omvolume systemd[1]: Started Terminate Plymouth Boot Screen. May 01 23:34:24 omvolume systemd[1]: cd.service holdoff time over, scheduling restart. May 01 23:34:24 omvolume dbus[642]: [system] Successfully activated service 'org.freedesktop.UDisks' May 01 23:34:24 omvolume cpufrequtils[730]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 01 23:34:24 omvolume avahi-daemon[639]: Server startup complete. Host name is omvolume.local. Local service cookie is 3253493040. May 01 23:34:24 omvolume systemd[1]: Started LSB: set CPUFreq kernel parameters. May 01 23:34:24 omvolume systemd[1]: Started Disk Manager (legacy version). May 01 23:34:24 omvolume systemd[1]: Stopping cd.service... May 01 23:34:24 omvolume systemd[1]: Starting cd.service... May 01 23:34:24 omvolume systemd[1]: Started cd.service. May 01 23:34:24 omvolume systemd[1]: Starting Getty on tty1... May 01 23:34:24 omvolume volumio[750]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 01 23:34:24 omvolume systemd[1]: Started Getty on tty1. May 01 23:34:24 omvolume systemd[1]: Starting Serial Getty on ttyAMA0... May 01 23:34:24 omvolume systemd[1]: Started Serial Getty on ttyAMA0. May 01 23:34:24 omvolume systemd[1]: Starting Login Prompts. May 01 23:34:24 omvolume systemd[1]: Reached target Login Prompts. May 01 23:34:24 omvolume systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 01 23:34:24 omvolume systemd[1]: Unit cd.service entered failed state. May 01 23:34:24 omvolume volumio-remote-updater[604]: Error: No active session May 01 23:34:24 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:24] [info] asio async_connect error: system:111 (Connection refused) May 01 23:34:24 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 01 23:34:24 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:24] [error] handle_connect error: Underlying Transport Error May 01 23:34:24 omvolume systemd[1]: cd.service holdoff time over, scheduling restart. May 01 23:34:24 omvolume systemd[1]: Stopping cd.service... May 01 23:34:24 omvolume systemd[1]: Starting cd.service... May 01 23:34:24 omvolume systemd[1]: Started cd.service. May 01 23:34:24 omvolume dbus[642]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' May 01 23:34:24 omvolume volumio[757]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 01 23:34:24 omvolume systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 01 23:34:24 omvolume systemd[1]: Unit cd.service entered failed state. May 01 23:34:24 omvolume systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... May 01 23:34:24 omvolume volumio[603]: Could not open config: /tmp/upmpdcli.conf May 01 23:34:24 omvolume systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE May 01 23:34:24 omvolume systemd[1]: Unit upmpdcli.service entered failed state. May 01 23:34:25 omvolume systemd[1]: cd.service holdoff time over, scheduling restart. May 01 23:34:25 omvolume systemd[1]: Stopping cd.service... May 01 23:34:25 omvolume systemd[1]: Starting cd.service... May 01 23:34:25 omvolume systemd[1]: Started cd.service. May 01 23:34:25 omvolume volumio[763]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 01 23:34:25 omvolume systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 01 23:34:25 omvolume systemd[1]: Unit cd.service entered failed state. May 01 23:34:25 omvolume polkitd[759]: started daemon version 0.105 using authority implementation `local' version `0.105' May 01 23:34:25 omvolume dbus[642]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 01 23:34:25 omvolume systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. May 01 23:34:25 omvolume udisks-glue[601]: Device file /dev/mmcblk0 inserted May 01 23:34:25 omvolume udisks-glue[601]: Device file /dev/mmcblk0p3 inserted May 01 23:34:25 omvolume avahi-daemon[639]: Service "omvolume" (/services/volumio.service) successfully established. May 01 23:34:25 omvolume systemd[1]: cd.service holdoff time over, scheduling restart. May 01 23:34:25 omvolume systemd[1]: Stopping cd.service... May 01 23:34:25 omvolume systemd[1]: Starting cd.service... May 01 23:34:25 omvolume systemd[1]: cd.service start request repeated too quickly, refusing to start. May 01 23:34:25 omvolume systemd[1]: Failed to start cd.service. May 01 23:34:25 omvolume systemd[1]: Unit cd.service entered failed state. May 01 23:34:25 omvolume haveged[598]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K May 01 23:34:25 omvolume haveged[598]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 May 01 23:34:25 omvolume haveged[598]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9983 May 01 23:34:25 omvolume haveged[598]: haveged: fills: 0, generated: 0 May 01 23:34:25 omvolume kernel: random: crng init done May 01 23:34:25 omvolume kernel: random: 7 urandom warning(s) missed due to ratelimiting May 01 23:34:25 omvolume systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 01 23:34:25 omvolume systemd[1]: Stopping Xbindrc... May 01 23:34:25 omvolume systemd[1]: Starting Xbindrc... May 01 23:34:25 omvolume systemd[1]: Started Xbindrc. May 01 23:34:26 omvolume systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 01 23:34:26 omvolume systemd[1]: Stopping Xbindrc... May 01 23:34:26 omvolume systemd[1]: Starting Xbindrc... May 01 23:34:26 omvolume systemd[1]: Started Xbindrc. May 01 23:34:26 omvolume systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 01 23:34:26 omvolume systemd[1]: Stopping Xbindrc... May 01 23:34:26 omvolume systemd[1]: Starting Xbindrc... May 01 23:34:26 omvolume systemd[1]: Started Xbindrc. May 01 23:34:27 omvolume systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 01 23:34:27 omvolume systemd[1]: Stopping Xbindrc... May 01 23:34:27 omvolume systemd[1]: Starting Xbindrc... May 01 23:34:27 omvolume systemd[1]: Started Xbindrc. May 01 23:34:27 omvolume systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 01 23:34:27 omvolume systemd[1]: Stopping Xbindrc... May 01 23:34:27 omvolume systemd[1]: Starting Xbindrc... May 01 23:34:27 omvolume systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. May 01 23:34:27 omvolume systemd[1]: Failed to start Xbindrc. May 01 23:34:27 omvolume systemd[1]: Unit xbindkeysrc.service entered failed state. May 01 23:34:29 omvolume wireless.js[721]: WIRELESS: No wireless interface, exiting May 01 23:34:29 omvolume systemd[1]: wireless.service: control process exited, code=exited status=1 May 01 23:34:29 omvolume systemd[1]: Failed to start Wireless Services. May 01 23:34:29 omvolume systemd[1]: Unit wireless.service entered failed state. May 01 23:34:29 omvolume systemd[1]: Starting Volumio Backend Module... May 01 23:34:29 omvolume systemd[1]: Started Volumio Backend Module. May 01 23:34:29 omvolume systemd[1]: Starting Volumio Streaming Daemon... May 01 23:34:29 omvolume systemd[1]: Started Volumio Streaming Daemon. May 01 23:34:29 omvolume systemd[1]: Started Music Player Daemon. May 01 23:34:29 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:29] [info] asio async_connect error: system:111 (Connection refused) May 01 23:34:29 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 01 23:34:29 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:29] [error] handle_connect error: Underlying Transport Error May 01 23:34:29 omvolume volumio-streaming-daemon[798]: ############################ May 01 23:34:29 omvolume volumio-streaming-daemon[798]: # Volumio Streaming Daemon # May 01 23:34:29 omvolume volumio-streaming-daemon[798]: # Running on port 7777 # May 01 23:34:29 omvolume volumio-streaming-daemon[798]: ############################ May 01 23:34:29 omvolume systemd[1]: Started LSB: start Samba daemons for the AD DC. May 01 23:34:30 omvolume winbind[615]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory May 01 23:34:30 omvolume winbind[615]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 01 23:34:30 omvolume winbind[615]: Unable to setup corepath for winbindd: No such file or directory May 01 23:34:30 omvolume winbind[615]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 01 23:34:30 omvolume winbind[615]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 01 23:34:30 omvolume winbind[615]: Unable to setup corepath for winbindd: No such file or directory May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume winbind[615]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume winbind[615]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30, 0] ../source3/winbindd/winbindd.c:1549(main) May 01 23:34:30 omvolume winbind[615]: winbindd version 4.2.14-Debian started. May 01 23:34:30 omvolume winbind[615]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30, 0] ../lib/util/util.c:220(directory_create_or_exist) May 01 23:34:30 omvolume winbind[615]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 01 23:34:30 omvolume winbind[615]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 01 23:34:30 omvolume winbind[615]: Unable to setup corepath for winbindd: No such file or directory May 01 23:34:30 omvolume winbindd[824]: [2024/05/01 23:34:30.256580, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume winbindd[824]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 01 23:34:30 omvolume winbindd[824]: [2024/05/01 23:34:30.258582, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume winbindd[824]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 01 23:34:30 omvolume nmbd[827]: [2024/05/01 23:34:30.283064, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume nmbd[827]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 01 23:34:30 omvolume nmbd[827]: [2024/05/01 23:34:30.290779, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume nmbd[827]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 01 23:34:30 omvolume winbindd[828]: [2024/05/01 23:34:30.291645, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) May 01 23:34:30 omvolume winbindd[828]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 01 23:34:30 omvolume nmbd[829]: [2024/05/01 23:34:30.296880, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) May 01 23:34:30 omvolume nmbd[829]: started asyncdns process 830 May 01 23:34:30 omvolume systemd[1]: Started LSB: start Winbind daemon. May 01 23:34:30 omvolume systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). May 01 23:34:30 omvolume systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30.256580, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume winbind[615]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 01 23:34:30 omvolume winbind[615]: [2024/05/01 23:34:30.258582, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume winbind[615]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 01 23:34:30 omvolume nmbd[620]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory May 01 23:34:30 omvolume nmbd[620]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 01 23:34:30 omvolume nmbd[620]: Unable to setup corepath for nmbd: No such file or directory May 01 23:34:30 omvolume nmbd[620]: [2024/05/01 23:34:30, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume nmbd[620]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 01 23:34:30 omvolume nmbd[620]: [2024/05/01 23:34:30, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume nmbd[620]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 01 23:34:30 omvolume nmbd[620]: [2024/05/01 23:34:30, 0] ../source3/nmbd/nmbd.c:908(main) May 01 23:34:30 omvolume nmbd[620]: nmbd version 4.2.14-Debian started. May 01 23:34:30 omvolume nmbd[620]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 01 23:34:30 omvolume nmbd[620]: [2024/05/01 23:34:30.283064, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume nmbd[620]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 01 23:34:30 omvolume nmbd[620]: [2024/05/01 23:34:30.290779, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:30 omvolume nmbd[620]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 01 23:34:30 omvolume winbind[615]: . May 01 23:34:30 omvolume nmbd[620]: . May 01 23:34:30 omvolume nmbd[829]: [2024/05/01 23:34:30.341350, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 01 23:34:30 omvolume nmbd[829]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 01 23:34:53 omvolume winbindd[828]: [2024/05/01 23:34:53.084477, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 01 23:34:53 omvolume winbindd[828]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 01 23:34:53 omvolume winbindd[838]: [2024/05/01 23:34:53.093791, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:53 omvolume winbindd[838]: Unable to open new log file '/var/log/samba/log.wb-OMVOLUME': No such file or directory May 01 23:34:53 omvolume systemd[1]: Time has been changed May 01 23:34:54 omvolume smbd[831]: Starting SMB/CIFS daemon: smbd[2024/05/01 23:34:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[831]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54, 0] ../lib/util/util.c:220(directory_create_or_exist) May 01 23:34:54 omvolume smbd[831]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 01 23:34:54 omvolume smbd[831]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 01 23:34:54 omvolume smbd[831]: Unable to setup corepath for smbd: No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[831]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54, 0] ../source3/smbd/server.c:1241(main) May 01 23:34:54 omvolume smbd[831]: smbd version 4.2.14-Debian started. May 01 23:34:54 omvolume smbd[831]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 01 23:34:54 omvolume smbd[842]: [2024/05/01 23:34:54.622505, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[842]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[842]: [2024/05/01 23:34:54.625178, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[842]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[842]: [2024/05/01 23:34:54.625634, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[842]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54.622505, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[831]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54.625178, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[831]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume smbd[831]: [2024/05/01 23:34:54.625634, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[831]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:54 omvolume systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). May 01 23:34:54 omvolume systemd[1]: Starting Multi-User System. May 01 23:34:54 omvolume systemd[1]: Reached target Multi-User System. May 01 23:34:54 omvolume systemd[1]: Starting Graphical Interface. May 01 23:34:54 omvolume systemd[1]: Reached target Graphical Interface. May 01 23:34:54 omvolume systemd[1]: Starting Update UTMP about System Runlevel Changes... May 01 23:34:54 omvolume smbd[831]: . May 01 23:34:54 omvolume systemd[1]: Started Update UTMP about System Runlevel Changes. May 01 23:34:54 omvolume systemd[1]: Startup finished in 9.723s (kernel) + 24.911s (userspace) = 34.634s. May 01 23:34:54 omvolume winbindd[849]: [2024/05/01 23:34:54.731064, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume winbindd[849]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory May 01 23:34:54 omvolume winbindd[850]: [2024/05/01 23:34:54.797888, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume winbindd[850]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory May 01 23:34:54 omvolume smbd[843]: [2024/05/01 23:34:54.877106, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 01 23:34:54 omvolume smbd[843]: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 01 23:34:54 omvolume smbd[851]: [2024/05/01 23:34:54.884951, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 01 23:34:54 omvolume smbd[851]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 01 23:34:57 omvolume volumio[797]: info: ------------------------------------------- May 01 23:34:57 omvolume volumio[797]: info: ----- Volumio2 ---- May 01 23:34:57 omvolume volumio[797]: info: ------------------------------------------- May 01 23:34:57 omvolume volumio[797]: info: ----- System startup ---- May 01 23:34:57 omvolume volumio[797]: info: ------------------------------------------- May 01 23:34:57 omvolume volumio[797]: info: MYVOLUMIO Environment detected May 01 23:34:59 omvolume volumio[797]: info: Plugin folders cleanup May 01 23:34:59 omvolume volumio[797]: info: Scanning into folder /volumio/app/plugins/ May 01 23:34:59 omvolume volumio[797]: info: Scanning category audio_interface May 01 23:34:59 omvolume volumio[797]: info: Scanning category miscellanea May 01 23:34:59 omvolume volumio[797]: info: Scanning category music_service May 01 23:34:59 omvolume volumio[797]: info: Scanning category plugins.json May 01 23:34:59 omvolume volumio[797]: info: Scanning category system_controller May 01 23:34:59 omvolume volumio[797]: info: Scanning category user_interface May 01 23:34:59 omvolume volumio[797]: info: Scanning into folder /data/plugins/ May 01 23:34:59 omvolume volumio[797]: info: Scanning category music_service May 01 23:34:59 omvolume volumio[797]: info: Plugin folders cleanup completed May 01 23:34:59 omvolume volumio[797]: info: ------------------------------------------- May 01 23:34:59 omvolume volumio[797]: info: ----- Core plugins startup ---- May 01 23:34:59 omvolume volumio[797]: info: ------------------------------------------- May 01 23:34:59 omvolume volumio[797]: info: Loading plugins from folder /volumio/app/plugins/ May 01 23:34:59 omvolume volumio[797]: info: Adding plugin upnp to MyMusic Plugins May 01 23:34:59 omvolume volumio[797]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 23:34:59 omvolume volumio[797]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 23:34:59 omvolume volumio[797]: info: Loading plugins from folder /data/plugins/ May 01 23:34:59 omvolume volumio[797]: info: Loading plugin "system"... May 01 23:34:59 omvolume volumio[797]: info: Loading plugin "appearance"... May 01 23:34:59 omvolume volumio-remote-updater[604]: [2024-05-01 23:34:59] [connect] Successful connection May 01 23:35:01 omvolume volumio[797]: info: Loading plugin "network"... May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "services"... May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "alsa_controller"... May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "wizard"... May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "volumio_command_line_client"... May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "upnp"... May 01 23:35:02 omvolume volumio[797]: info: [1714606502724] Starting Upmpd Daemon May 01 23:35:02 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "my_music"... May 01 23:35:02 omvolume volumio[797]: info: Loading plugin "mpd"... May 01 23:35:03 omvolume sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 23:35:03 omvolume volumio[797]: info: Loading plugin "upnp_browser"... May 01 23:35:03 omvolume sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 23:35:03 omvolume sudo[870]: pam_unix(sudo:session): session closed for user root May 01 23:35:03 omvolume sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 23:35:03 omvolume sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 23:35:03 omvolume systemd[1]: Stopping Music Player Daemon... May 01 23:35:03 omvolume systemd[1]: Starting Music Player Daemon... May 01 23:35:04 omvolume systemd[1]: Started Music Player Daemon. May 01 23:35:04 omvolume sudo[873]: pam_unix(sudo:session): session closed for user root May 01 23:35:04 omvolume volumio[797]: info: Loading plugin "networkfs"... May 01 23:35:05 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 23:35:05 omvolume volumio[797]: info: Loading plugin "alarm-clock"... May 01 23:35:05 omvolume sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=devin,password=2420N0Va,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //OPENMEDIAVAULT/music /mnt/NAS/music May 01 23:35:05 omvolume sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 23:35:05 omvolume sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Devin,password=2420N0Va,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.253/Music/ /mnt/NAS/Raspberrypiomv May 01 23:35:05 omvolume sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 23:35:05 omvolume kernel: FS-Cache: Netfs 'cifs' registered for caching May 01 23:35:05 omvolume kernel: Key type cifs.spnego registered May 01 23:35:05 omvolume kernel: Key type cifs.idmap registered May 01 23:35:05 omvolume 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. May 01 23:35:05 omvolume 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. May 01 23:35:05 omvolume volumio[797]: info: Plugin airplay_emulation is not enabled May 01 23:35:05 omvolume volumio[797]: info: Loading plugin "last_100"... May 01 23:35:05 omvolume volumio[797]: info: Loading plugin "webradio"... May 01 23:35:05 omvolume sudo[894]: pam_unix(sudo:session): session closed for user root May 01 23:35:05 omvolume sudo[896]: pam_unix(sudo:session): session closed for user root May 01 23:35:06 omvolume volumio[797]: info: Loading plugin "i2s_dacs"... May 01 23:35:06 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 23:35:06 omvolume volumio[797]: info: Loading plugin "volumiodiscovery"... May 01 23:35:06 omvolume volumio[797]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 23:35:06 omvolume node[797]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 23:35:06 omvolume node[797]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 23:35:06 omvolume node[797]: *** WARNING *** For more information see May 01 23:35:06 omvolume node[797]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 23:35:06 omvolume node[797]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 23:35:06 omvolume node[797]: *** WARNING *** For more information see May 01 23:35:06 omvolume volumio[797]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 23:35:06 omvolume volumio[797]: *** WARNING *** For more information see May 01 23:35:06 omvolume volumio[797]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 23:35:06 omvolume volumio[797]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 23:35:06 omvolume volumio[797]: *** WARNING *** For more information see May 01 23:35:06 omvolume volumio[797]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 23:35:06 omvolume volumio[797]: Discovery: StartAdv! undefined May 01 23:35:06 omvolume volumio[797]: Discovery: Started advertising... omvolume - undefined May 01 23:35:06 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 23:35:06 omvolume volumio[797]: info: Loading plugin "jellyfin"... May 01 23:35:07 omvolume volumio[797]: info: Loading plugin "outputs"... May 01 23:35:07 omvolume volumio[797]: info: Loading plugin "albumart"... May 01 23:35:07 omvolume volumio[797]: info: Plugin example_plugin is not enabled May 01 23:35:07 omvolume volumio[797]: info: Loading plugin "inputs"... May 01 23:35:07 omvolume volumio[797]: info: Loading plugin "updater_comm"... May 01 23:35:07 omvolume volumio[797]: info: Plugin mpdemulation is not enabled May 01 23:35:07 omvolume volumio[797]: info: Loading plugin "rest_api"... May 01 23:35:07 omvolume volumio[797]: info: Loading plugin "websocket"... May 01 23:35:07 omvolume volumio[797]: info: ___________ START PLUGINS ___________ May 01 23:35:07 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 23:35:07 omvolume volumio[797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 23:35:07 omvolume volumio[797]: info: [1714606507973] CoreMusicLibrary::Adding element Media Servers May 01 23:35:07 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 23:35:08 omvolume volumio[797]: info: [1714606508033] CoreMusicLibrary::Adding element Last_100 May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 23:35:08 omvolume volumio[797]: info: [1714606508041] CoreMusicLibrary::Adding element Webradio May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 23:35:08 omvolume volumio[797]: Begin ConnectionManager constructor May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 23:35:08 omvolume volumio[797]: info: [1714606508089] CoreMusicLibrary::Adding element Jellyfin May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 23:35:08 omvolume volumio[797]: info: [jellyfin-poller-worker] start(): Started polling... May 01 23:35:08 omvolume volumio[797]: info: ------------------------------------------- May 01 23:35:08 omvolume volumio[797]: info: ----- MyVolumio plugins startup ---- May 01 23:35:08 omvolume volumio[797]: info: ------------------------------------------- May 01 23:35:08 omvolume volumio[797]: info: [MyVolumio PluginManager] Fetching plans data.... May 01 23:35:08 omvolume volumio[797]: Forking 3 albumart workers May 01 23:35:08 omvolume volumio[797]: info: Loading i18n strings for locale en May 01 23:35:08 omvolume volumio[797]: Updating browse sources language May 01 23:35:08 omvolume volumio[797]: Cannot find translation for sourceMedia Servers May 01 23:35:08 omvolume volumio[797]: Cannot find translation for sourceJellyfin May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::initPlayerControls May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 23:35:08 omvolume volumio[797]: info: BOOT COMPLETED May 01 23:35:08 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 23:35:09 omvolume volumio[797]: Starting albumart workers May 01 23:35:09 omvolume volumio[797]: Starting albumart workers May 01 23:35:09 omvolume volumio[797]: Starting albumart workers May 01 23:35:13 omvolume volumio[797]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 01 23:35:13 omvolume volumio[797]: info: CoreCommandRouter::Close All Modals sent May 01 23:35:13 omvolume volumio[797]: info: CoreCommandRouter::Close All Modals sent May 01 23:35:13 omvolume volumio[797]: Express server listening on port 3000 May 01 23:35:13 omvolume volumio[797]: Volumio Calling Home May 01 23:35:13 omvolume volumio[797]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. May 01 23:35:13 omvolume volumio[797]: info: CoreStateMachine::resetVolumioState May 01 23:35:13 omvolume volumio[797]: info: CoreStateMachine::getcurrentVolume May 01 23:35:13 omvolume volumio[797]: info: CoreCommandRouter::volumioRetrievevolume May 01 23:35:14 omvolume sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 23:35:14 omvolume sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 23:35:14 omvolume sudo[1012]: pam_unix(sudo:session): session closed for user root May 01 23:35:14 omvolume volumio[797]: info: Setting Device type: Raspberry PI May 01 23:35:14 omvolume volumio[797]: info: MPD Permissions set May 01 23:35:14 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 01 23:35:14 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 01 23:35:14 omvolume volumio-remote-updater[604]: [2024-05-01 23:35:14] [connect] Successful connection May 01 23:35:15 omvolume volumio[797]: info: MPD running with PID885 ,establishing connection May 01 23:35:15 omvolume volumio[797]: info: VolumeController:: Volume=65 Mute =false May 01 23:35:15 omvolume volumio[797]: info: CoreStateMachine::pushState May 01 23:35:15 omvolume volumio[797]: info: CoreStateMachine::getState May 01 23:35:15 omvolume volumio[797]: info: CorePlayQueue::getTrack 0 May 01 23:35:15 omvolume volumio[797]: info: CoreCommandRouter::volumioPushState May 01 23:35:15 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 23:35:15 omvolume volumio[797]: info: CoreStateMachine::updateTrackBlock May 01 23:35:15 omvolume volumio[797]: info: CorePlayQueue::getTrackBlock May 01 23:35:15 omvolume volumio[797]: info: CoreCommandRouter::volumioRetrievevolume May 01 23:35:15 omvolume volumio[797]: info: May 01 23:35:15 omvolume volumio[797]: ---------------------------- Client requests Volume 25 May 01 23:35:15 omvolume volumio[797]: info: VolumeController::SetAlsaVolume25 May 01 23:35:15 omvolume volumio[797]: info: CoreStateMachine::pushState May 01 23:35:15 omvolume volumio[797]: info: CoreStateMachine::getState May 01 23:35:15 omvolume volumio[797]: info: CorePlayQueue::getTrack 0 May 01 23:35:15 omvolume volumio[797]: info: CoreCommandRouter::volumioPushState May 01 23:35:15 omvolume volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 23:35:15 omvolume volumio[797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 23:35:15 omvolume volumio[797]: { AssertionError [ERR_ASSERTION]: false == true May 01 23:35:15 omvolume volumio[797]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) May 01 23:35:15 omvolume volumio[797]: at /volumio/app/plugins/music_service/mpd/index.js:3118:28 May 01 23:35:15 omvolume volumio[797]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 01 23:35:15 omvolume volumio[797]: at process._tickCallback (internal/process/next_tick.js:180:9) May 01 23:35:15 omvolume volumio[797]: generatedMessage: true, May 01 23:35:15 omvolume volumio[797]: name: 'AssertionError [ERR_ASSERTION]', May 01 23:35:15 omvolume volumio[797]: code: 'ERR_ASSERTION', May 01 23:35:15 omvolume volumio[797]: actual: false, May 01 23:35:15 omvolume volumio[797]: expected: true, May 01 23:35:15 omvolume volumio[797]: operator: '==' } May 01 23:35:15 omvolume volumio[797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 23:35:17 omvolume sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 23:34 May 01 23:35:17 omvolume sudo[1039]: 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="80e3a7415791a01dac7cd7cc2cc0a3d3fc65ba9d" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="a5d5fb6aa4e4c71be85580a005adf6b2009366a5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jan 16 20:23:20 CET 2020" VOLUMIO_VERSION="2.698" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="3dcbb012a2043e1c4181cb54ce618cae"