-- Logs begin at Thu 2019-02-14 10:12:01 UTC, end at Sun 2024-08-18 08:54:51 UTC. -- Aug 18 08:53:06 volumio-wz kernel: usb usb1: SerialNumber: 3f980000.usb Aug 18 08:53:06 volumio-wz kernel: hub 1-0:1.0: USB hub found Aug 18 08:53:06 volumio-wz kernel: hub 1-0:1.0: 1 port detected Aug 18 08:53:06 volumio-wz kernel: dwc_otg: FIQ enabled Aug 18 08:53:06 volumio-wz kernel: dwc_otg: NAK holdoff enabled Aug 18 08:53:06 volumio-wz kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 18 08:53:06 volumio-wz kernel: Module dwc_common_port init Aug 18 08:53:06 volumio-wz kernel: usbcore: registered new interface driver usb-storage Aug 18 08:53:06 volumio-wz kernel: mousedev: PS/2 mouse device common for all mice Aug 18 08:53:06 volumio-wz kernel: sdhci: Secure Digital Host Controller Interface driver Aug 18 08:53:06 volumio-wz kernel: sdhci: Copyright(c) Pierre Ossman Aug 18 08:53:06 volumio-wz kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 18 08:53:06 volumio-wz kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 18 08:53:06 volumio-wz kernel: hid: raw HID events driver (C) Jiri Kosina Aug 18 08:53:06 volumio-wz kernel: usbcore: registered new interface driver usbhid Aug 18 08:53:06 volumio-wz kernel: usbhid: USB HID core driver Aug 18 08:53:06 volumio-wz kernel: Initializing XFRM netlink socket Aug 18 08:53:06 volumio-wz kernel: NET: Registered PF_PACKET protocol family Aug 18 08:53:06 volumio-wz kernel: Key type dns_resolver registered Aug 18 08:53:06 volumio-wz kernel: Registering SWP/SWPB emulation handler Aug 18 08:53:06 volumio-wz kernel: registered taskstats version 1 Aug 18 08:53:06 volumio-wz kernel: Loading compiled-in X.509 certificates Aug 18 08:53:06 volumio-wz kernel: Key type .fscrypt registered Aug 18 08:53:06 volumio-wz kernel: Key type fscrypt-provisioning registered Aug 18 08:53:06 volumio-wz kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Aug 18 08:53:06 volumio-wz kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Aug 18 08:53:06 volumio-wz kernel: printk: console [ttyAMA0] enabled Aug 18 08:53:06 volumio-wz kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 18 08:53:06 volumio-wz kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Aug 18 08:53:06 volumio-wz kernel: sdhost: log_buf @ 5b7b95ca (f4113000) Aug 18 08:53:06 volumio-wz kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Aug 18 08:53:06 volumio-wz kernel: of_cfs_init Aug 18 08:53:06 volumio-wz kernel: of_cfs_init: OK Aug 18 08:53:06 volumio-wz kernel: Freeing unused kernel image (initmem) memory: 1024K Aug 18 08:53:06 volumio-wz kernel: Indeed it is in host mode hprt0 = 00021501 Aug 18 08:53:06 volumio-wz kernel: Run /init as init process Aug 18 08:53:06 volumio-wz kernel: with arguments: Aug 18 08:53:06 volumio-wz kernel: /init Aug 18 08:53:06 volumio-wz kernel: splash Aug 18 08:53:06 volumio-wz kernel: nodebug Aug 18 08:53:06 volumio-wz kernel: with environment: Aug 18 08:53:06 volumio-wz kernel: HOME=/ Aug 18 08:53:06 volumio-wz kernel: TERM=linux Aug 18 08:53:06 volumio-wz kernel: imgpart=UUID=133b61d2-daf2-4237-92be-25beb421aa33 Aug 18 08:53:06 volumio-wz kernel: imgfile=/volumio_current.sqsh Aug 18 08:53:06 volumio-wz kernel: bootpart=UUID=6C7C-4C43 Aug 18 08:53:06 volumio-wz kernel: datapart=UUID=5caf4c4e-188e-4b64-b123-95a48a4d4e8c Aug 18 08:53:06 volumio-wz kernel: uuidconfig=cmdline.txt Aug 18 08:53:06 volumio-wz kernel: pcie_aspm=off Aug 18 08:53:06 volumio-wz kernel: pci=pcie_bus_safe Aug 18 08:53:06 volumio-wz kernel: bootdelay=7 Aug 18 08:53:06 volumio-wz kernel: use_kmsg=no Aug 18 08:53:06 volumio-wz kernel: mmc0: host does not support reading read-only switch, assuming write-enable Aug 18 08:53:06 volumio-wz kernel: mmc0: new high speed SDHC card at address aaaa Aug 18 08:53:06 volumio-wz kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Aug 18 08:53:06 volumio-wz kernel: mmcblk0: p1 p2 p3 Aug 18 08:53:06 volumio-wz kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB (quirks 0x00004000) Aug 18 08:53:06 volumio-wz kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Aug 18 08:53:06 volumio-wz kernel: Indeed it is in host mode hprt0 = 00001101 Aug 18 08:53:06 volumio-wz kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Aug 18 08:53:06 volumio-wz kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 18 08:53:06 volumio-wz kernel: hub 1-1:1.0: USB hub found Aug 18 08:53:06 volumio-wz kernel: hub 1-1:1.0: 5 ports detected Aug 18 08:53:06 volumio-wz kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Aug 18 08:53:06 volumio-wz kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Aug 18 08:53:06 volumio-wz kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Aug 18 08:53:06 volumio-wz kernel: smsc95xx v2.0.0 Aug 18 08:53:06 volumio-wz kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Aug 18 08:53:06 volumio-wz kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:48:46:85 Aug 18 08:53:06 volumio-wz kernel: usb 1-1.3: new full-speed USB device number 4 using dwc_otg Aug 18 08:53:06 volumio-wz kernel: fuse: init (API version 7.37) Aug 18 08:53:06 volumio-wz kernel: usb 1-1.3: New USB device found, idVendor=0a12, idProduct=0001, bcdDevice=88.91 Aug 18 08:53:06 volumio-wz kernel: usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Aug 18 08:53:06 volumio-wz kernel: usb 1-1.3: Product: CSR8510 A10 Aug 18 08:53:06 volumio-wz kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 18 08:53:06 volumio-wz kernel: usbcore: registered new interface driver uas Aug 18 08:53:06 volumio-wz kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 18 08:53:06 volumio-wz kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 18 08:53:06 volumio-wz kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Aug 18 08:53:06 volumio-wz kernel: loop0: detected capacity change from 0 to 983360 Aug 18 08:53:06 volumio-wz kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 18 08:53:06 volumio-wz kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Aug 18 08:53:06 volumio-wz systemd[1]: System time before build time, advancing clock. Aug 18 08:53:06 volumio-wz kernel: NET: Registered PF_INET6 protocol family Aug 18 08:53:06 volumio-wz kernel: Segment Routing with IPv6 Aug 18 08:53:06 volumio-wz kernel: In-situ OAM (IOAM) with IPv6 Aug 18 08:53:06 volumio-wz systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Aug 18 08:53:06 volumio-wz systemd[1]: Detected architecture arm. Aug 18 08:53:06 volumio-wz systemd[1]: Set hostname to . Aug 18 08:53:06 volumio-wz kernel: uart-pl011 3f201000.serial: no DMA platform data Aug 18 08:53:06 volumio-wz systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 18 08:53:06 volumio-wz systemd[1]: Listening on Journal Audit Socket. Aug 18 08:53:06 volumio-wz systemd[1]: Listening on Journal Socket. Aug 18 08:53:06 volumio-wz systemd[1]: Created slice system-getty.slice. Aug 18 08:53:06 volumio-wz systemd[1]: Starting Remount Root and Kernel File Systems... Aug 18 08:53:06 volumio-wz systemd[1]: Starting Load Kernel Modules... Aug 18 08:53:06 volumio-wz systemd[1]: Starting Create list of required static device nodes for the current kernel... Aug 18 08:53:06 volumio-wz kernel: i2c_dev: i2c /dev entries driver Aug 18 08:53:06 volumio-wz systemd-journald[296]: Journal started Aug 18 08:53:06 volumio-wz systemd-journald[296]: Runtime journal (/run/log/journal/f67eb8adba804d11b617d1be61ee763c) is 7.5M, max 30.0M, 22.5M free. Aug 18 08:53:06 volumio-wz systemd-modules-load[283]: Inserted module 'i2c_dev' Aug 18 08:53:06 volumio-wz fake-hwclock[297]: Sun Aug 18 08:53:06 UTC 2024 Aug 18 08:53:06 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:53:06 volumio-wz systemd[1]: Started Create System Users. Aug 18 08:53:06 volumio-wz systemd[1]: Starting Create Static Device Nodes in /dev... Aug 18 08:53:06 volumio-wz systemd[1]: Started Create Static Device Nodes in /dev. Aug 18 08:53:06 volumio-wz systemd[1]: Reached target Local File Systems (Pre). Aug 18 08:53:06 volumio-wz systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 18 08:53:06 volumio-wz systemd[1]: Mounting /var/log... Aug 18 08:53:06 volumio-wz systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 18 08:53:06 volumio-wz systemd[1]: Mounting /tmp... Aug 18 08:53:06 volumio-wz systemd[1]: Mounting /var/spool/cups... Aug 18 08:53:06 volumio-wz systemd[1]: Starting udev Kernel Device Manager... Aug 18 08:53:06 volumio-wz systemd[1]: Mounted /var/log. Aug 18 08:53:06 volumio-wz systemd[1]: Mounted /tmp. Aug 18 08:53:06 volumio-wz systemd[1]: Mounted /var/spool/cups. Aug 18 08:53:06 volumio-wz systemd[1]: Mounting /var/spool/cups/tmp... Aug 18 08:53:06 volumio-wz systemd[1]: Starting Flush Journal to Persistent Storage... Aug 18 08:53:06 volumio-wz systemd[1]: Mounted /var/spool/cups/tmp. Aug 18 08:53:06 volumio-wz systemd[1]: Started udev Coldplug all Devices. Aug 18 08:53:06 volumio-wz systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 18 08:53:06 volumio-wz systemd-udevd[320]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 18 08:53:06 volumio-wz systemd-journald[296]: Runtime journal (/run/log/journal/f67eb8adba804d11b617d1be61ee763c) is 7.5M, max 30.0M, 22.5M free. Aug 18 08:53:06 volumio-wz systemd[1]: Started Flush Journal to Persistent Storage. Aug 18 08:53:06 volumio-wz systemd[1]: Started udev Kernel Device Manager. Aug 18 08:53:06 volumio-wz systemd[1]: Starting Show Plymouth Boot Screen... Aug 18 08:53:07 volumio-wz systemd[1]: plymouth-start.service: Succeeded. Aug 18 08:53:07 volumio-wz systemd[1]: Started Show Plymouth Boot Screen. Aug 18 08:53:07 volumio-wz systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 18 08:53:07 volumio-wz systemd[1]: Reached target Local Encrypted Volumes. Aug 18 08:53:07 volumio-wz systemd[1]: Reached target Paths. Aug 18 08:53:07 volumio-wz systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 18 08:53:07 volumio-wz kernel: mc: Linux media interface: v0.10 Aug 18 08:53:07 volumio-wz kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:07 volumio-wz kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 18 08:53:07 volumio-wz kernel: [vc_sm_connected_init]: start Aug 18 08:53:07 volumio-wz kernel: [vc_sm_connected_init]: installed successfully Aug 18 08:53:07 volumio-wz kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Aug 18 08:53:07 volumio-wz kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Aug 18 08:53:07 volumio-wz systemd[1]: Found device /dev/ttyAMA0. Aug 18 08:53:07 volumio-wz kernel: videodev: Linux video capture interface: v2.00 Aug 18 08:53:08 volumio-wz systemd[1]: Found device /dev/disk/by-uuid/6C7C-4C43. Aug 18 08:53:08 volumio-wz kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz systemd-udevd[333]: Using default interface naming scheme 'v240'. Aug 18 08:53:08 volumio-wz kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 18 08:53:08 volumio-wz kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Aug 18 08:53:08 volumio-wz kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz systemd-udevd[333]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 18 08:53:08 volumio-wz kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Aug 18 08:53:08 volumio-wz kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 18 08:53:08 volumio-wz kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 18 08:53:08 volumio-wz kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Aug 18 08:53:08 volumio-wz kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Aug 18 08:53:08 volumio-wz kernel: Bluetooth: Core ver 2.22 Aug 18 08:53:08 volumio-wz kernel: NET: Registered PF_BLUETOOTH protocol family Aug 18 08:53:08 volumio-wz kernel: Bluetooth: HCI device and connection manager initialized Aug 18 08:53:08 volumio-wz kernel: Bluetooth: HCI socket layer initialized Aug 18 08:53:08 volumio-wz kernel: Bluetooth: L2CAP socket layer initialized Aug 18 08:53:08 volumio-wz kernel: Bluetooth: SCO socket layer initialized Aug 18 08:53:08 volumio-wz systemd-udevd[332]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 18 08:53:08 volumio-wz kernel: usbcore: registered new interface driver btusb Aug 18 08:53:08 volumio-wz kernel: Bluetooth: hci0: CSR: Setting up dongle with HCI ver=6 rev=22bb; LMP ver=6 subver=22bb; manufacturer=10 Aug 18 08:53:10 volumio-wz systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Aug 18 08:53:10 volumio-wz systemd[1]: Created slice system-bthelper.slice. Aug 18 08:53:10 volumio-wz systemd[1]: Starting Show Plymouth Boot Screen... Aug 18 08:53:10 volumio-wz systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 18 08:53:10 volumio-wz systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 18 08:53:10 volumio-wz systemd[1]: Condition check resulted in Huge Pages File System being skipped. Aug 18 08:53:10 volumio-wz systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 18 08:53:10 volumio-wz systemd[1]: Starting File System Check on /dev/disk/by-uuid/6C7C-4C43... Aug 18 08:53:10 volumio-wz systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 18 08:53:10 volumio-wz systemd[1]: plymouth-start.service: Succeeded. Aug 18 08:53:10 volumio-wz systemd[1]: Started Show Plymouth Boot Screen. Aug 18 08:53:10 volumio-wz systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 18 08:53:10 volumio-wz systemd[1]: Started File System Check Daemon to report status. Aug 18 08:53:10 volumio-wz systemd[1]: Started Load/Save RF Kill Switch Status. Aug 18 08:53:10 volumio-wz systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 18 08:53:11 volumio-wz systemd-fsck[414]: fsck.fat 4.1 (2017-01-24) Aug 18 08:53:11 volumio-wz systemd-fsck[414]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Aug 18 08:53:11 volumio-wz systemd-fsck[414]: Automatically removing dirty bit. Aug 18 08:53:11 volumio-wz systemd-fsck[414]: Performing changes. Aug 18 08:53:11 volumio-wz systemd-fsck[414]: /dev/mmcblk0p1: 379 files, 33710/46774 clusters Aug 18 08:53:11 volumio-wz systemd[1]: Started File System Check on /dev/disk/by-uuid/6C7C-4C43. Aug 18 08:53:11 volumio-wz systemd[1]: Mounting /boot... Aug 18 08:53:11 volumio-wz systemd[1]: Mounted /boot. Aug 18 08:53:11 volumio-wz systemd[1]: Reached target Local File Systems. Aug 18 08:53:11 volumio-wz systemd[1]: Starting Create Volatile Files and Directories... Aug 18 08:53:11 volumio-wz systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 18 08:53:11 volumio-wz systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 18 08:53:11 volumio-wz systemd[1]: Starting Raise network interfaces... Aug 18 08:53:11 volumio-wz systemd[1]: Starting Preprocess NFS configuration... Aug 18 08:53:11 volumio-wz systemd[1]: Started ifup for eth0. Aug 18 08:53:11 volumio-wz systemd[1]: Received SIGRTMIN+20 from PID 180 (plymouthd). Aug 18 08:53:11 volumio-wz systemd[1]: plymouth-read-write.service: Succeeded. Aug 18 08:53:11 volumio-wz systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 18 08:53:11 volumio-wz systemd[1]: nfs-config.service: Succeeded. Aug 18 08:53:11 volumio-wz systemd[1]: Started Preprocess NFS configuration. Aug 18 08:53:11 volumio-wz systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 18 08:53:11 volumio-wz systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 18 08:53:11 volumio-wz systemd[1]: Reached target NFS client services. Aug 18 08:53:11 volumio-wz systemd[1]: Started Create Volatile Files and Directories. Aug 18 08:53:11 volumio-wz systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 18 08:53:11 volumio-wz systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 18 08:53:11 volumio-wz systemd[1]: Starting RPC bind portmap service... Aug 18 08:53:11 volumio-wz systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 18 08:53:11 volumio-wz systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 18 08:53:11 volumio-wz systemd[1]: Reached target System Initialization. Aug 18 08:53:11 volumio-wz systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 18 08:53:11 volumio-wz systemd[1]: Listening on triggerhappy.socket. Aug 18 08:53:11 volumio-wz systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 18 08:53:11 volumio-wz systemd[1]: Started Periodic Checkmk agent update. Aug 18 08:53:11 volumio-wz systemd[1]: apt-daily.timer: Not using persistent file timestamp Mon 2024-10-21 18:00:22 UTC as it is in the future. Aug 18 08:53:11 volumio-wz systemd[1]: Started Daily apt download activities. Aug 18 08:53:11 volumio-wz systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2024-10-22 06:06:37 UTC as it is in the future. Aug 18 08:53:11 volumio-wz systemd[1]: Started Daily apt upgrade and clean activities. Aug 18 08:53:11 volumio-wz systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 18 08:53:11 volumio-wz systemd[1]: Reached target Timers. Aug 18 08:53:11 volumio-wz kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 18 08:53:12 volumio-wz kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Aug 18 08:53:12 volumio-wz kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Aug 18 08:53:12 volumio-wz kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 18 08:53:12 volumio-wz systemd[1]: Listening on TCP Checkmk Agent Socket. Aug 18 08:53:12 volumio-wz systemd[1]: Reached target Sockets. Aug 18 08:53:12 volumio-wz sh[427]: command failed: No such device (-19) Aug 18 08:53:12 volumio-wz systemd[1]: Reached target Basic System. Aug 18 08:53:12 volumio-wz systemd[1]: Started Volumio Backend Module. Aug 18 08:53:12 volumio-wz ifup[425]: command failed: No such device (-19) Aug 18 08:53:12 volumio-wz systemd[1]: Started volumio-remote-updater.service. Aug 18 08:53:12 volumio-wz systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 18 08:53:12 volumio-wz systemd[1]: Started UPnP Renderer front-end to MPD. Aug 18 08:53:12 volumio-wz systemd[1]: Started Volumio Iptables Module. Aug 18 08:53:12 volumio-wz systemd[1]: Started Manage Sound Card State (restore and store). Aug 18 08:53:12 volumio-wz systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 18 08:53:12 volumio-wz systemd[1]: Starting dhcpcd on all interfaces... Aug 18 08:53:12 volumio-wz systemd[1]: Starting triggerhappy global hotkey daemon... Aug 18 08:53:12 volumio-wz alsactl[474]: alsactl 1.1.8 daemon started Aug 18 08:53:12 volumio-wz systemd[1]: Starting Save/Restore Sound Card State... Aug 18 08:53:12 volumio-wz systemd[1]: Started Volumio Log Rotation Service. Aug 18 08:53:12 volumio-wz dhcpcd[476]: Not running dhcpcd because /etc/network/interfaces Aug 18 08:53:12 volumio-wz dhcpcd[476]: defines some interfaces that will use a Aug 18 08:53:12 volumio-wz dhcpcd[476]: DHCP client or static address Aug 18 08:53:12 volumio-wz systemd[1]: Started Volumio Cpu Tweaker. Aug 18 08:53:12 volumio-wz systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Aug 18 08:53:12 volumio-wz systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 18 08:53:12 volumio-wz systemd[1]: Starting Login Service... Aug 18 08:53:12 volumio-wz systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 18 08:53:12 volumio-wz systemd[1]: Started D-Bus System Message Bus. Aug 18 08:53:12 volumio-wz thd[477]: Unable to parse trigger line: Aug 18 08:53:12 volumio-wz thd[477]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 18 08:53:12 volumio-wz thd[477]: Unable to parse trigger line: Aug 18 08:53:12 volumio-wz thd[477]: Found socket passed from systemd Aug 18 08:53:12 volumio-wz systemd[1]: Starting WPA supplicant... Aug 18 08:53:12 volumio-wz systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 18 08:53:12 volumio-wz systemd[1]: Started RPC bind portmap service. Aug 18 08:53:12 volumio-wz systemd[1]: Started triggerhappy global hotkey daemon. Aug 18 08:53:12 volumio-wz systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Aug 18 08:53:12 volumio-wz systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Aug 18 08:53:12 volumio-wz systemd[1]: Failed to start dhcpcd on all interfaces. Aug 18 08:53:12 volumio-wz systemd[1]: Started Save/Restore Sound Card State. Aug 18 08:53:12 volumio-wz avahi-daemon[471]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Aug 18 08:53:12 volumio-wz avahi-daemon[471]: Successfully dropped root privileges. Aug 18 08:53:12 volumio-wz systemd[1]: Reached target Sound Card. Aug 18 08:53:12 volumio-wz systemd[1]: Reached target Remote File Systems (Pre). Aug 18 08:53:12 volumio-wz systemd[1]: Reached target Remote File Systems. Aug 18 08:53:12 volumio-wz avahi-daemon[471]: avahi-daemon 0.7 starting up. Aug 18 08:53:12 volumio-wz systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 18 08:53:12 volumio-wz systemd[1]: Reached target RPC Port Mapper. Aug 18 08:53:12 volumio-wz bthelper[484]: Not a UART-attached BT Modem Aug 18 08:53:13 volumio-wz ifup[425]: command failed: No such device (-19) Aug 18 08:53:13 volumio-wz systemd-logind[488]: New seat seat0. Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: Setting RT Priority for mpd Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: pid 35's current scheduling policy: SCHED_OTHER Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: pid 35's current scheduling priority: 0 Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: Setting MPD Affinity Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: pid 3's current affinity mask: f Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 18 08:53:13 volumio-wz volumio-cpu-tweak[483]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 18 08:53:13 volumio-wz kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 18 08:53:14 volumio-wz systemd[1]: Started Login Service. Aug 18 08:53:14 volumio-wz avahi-daemon[471]: Successfully called chroot(). Aug 18 08:53:14 volumio-wz systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 18 08:53:14 volumio-wz avahi-daemon[471]: Successfully dropped remaining capabilities. Aug 18 08:53:14 volumio-wz systemd[1]: Started Raspberry Pi bluetooth helper. Aug 18 08:53:14 volumio-wz wpa_supplicant[494]: Successfully initialized wpa_supplicant Aug 18 08:53:14 volumio-wz avahi-daemon[471]: Loading service file /services/volumio.service. Aug 18 08:53:14 volumio-wz avahi-daemon[471]: Network interface enumeration completed. Aug 18 08:53:14 volumio-wz avahi-daemon[471]: Server startup complete. Host name is volumio-wz.local. Local service cookie is 3727623633. Aug 18 08:53:14 volumio-wz avahi-daemon[471]: Service "volumio-wz" (/services/volumio.service) successfully established. Aug 18 08:53:14 volumio-wz systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 18 08:53:14 volumio-wz kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Aug 18 08:53:14 volumio-wz sh[427]: eth0: waiting for carrier Aug 18 08:53:14 volumio-wz dhcpcd[502]: eth0: waiting for carrier Aug 18 08:53:14 volumio-wz systemd[1]: Started WPA supplicant. Aug 18 08:53:14 volumio-wz kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Aug 18 08:53:14 volumio-wz ifup[425]: command failed: No such device (-19) Aug 18 08:53:14 volumio-wz systemd[1]: Starting Bluetooth service... Aug 18 08:53:14 volumio-wz volumio-remote-updater[466]: Error: No active session Aug 18 08:53:14 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:14] [info] asio async_connect error: system:111 (Connection refused) Aug 18 08:53:14 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 18 08:53:14 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:14] [error] handle_connect error: Underlying Transport Error Aug 18 08:53:14 volumio-wz volumio[472]: Could not open config: /tmp/upmpdcli.conf Aug 18 08:53:14 volumio-wz systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 18 08:53:14 volumio-wz systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 18 08:53:15 volumio-wz systemd[1]: iptables.service: Succeeded. Aug 18 08:53:15 volumio-wz bluetoothd[577]: Bluetooth daemon 5.50 Aug 18 08:53:15 volumio-wz bluetoothd[577]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 18 08:53:15 volumio-wz systemd[1]: Started Bluetooth service. Aug 18 08:53:15 volumio-wz systemd[1]: Reached target Bluetooth. Aug 18 08:53:15 volumio-wz bluetoothd[577]: Starting SDP server Aug 18 08:53:15 volumio-wz bluetoothd[577]: Excluding (cli) sap Aug 18 08:53:15 volumio-wz loadcpufreq[511]: Loading cpufreq kernel modules...done (none). Aug 18 08:53:15 volumio-wz systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 18 08:53:15 volumio-wz kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 18 08:53:15 volumio-wz kernel: Bluetooth: BNEP filters: protocol multicast Aug 18 08:53:15 volumio-wz kernel: Bluetooth: BNEP socket layer initialized Aug 18 08:53:15 volumio-wz bluetoothd[577]: Bluetooth management interface 1.22 initialized Aug 18 08:53:15 volumio-wz dbus-daemon[492]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=577 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 18 08:53:15 volumio-wz kernel: Bluetooth: MGMT ver 1.22 Aug 18 08:53:15 volumio-wz bluetoothd[577]: Failed to set privacy: Rejected (0x0b) Aug 18 08:53:15 volumio-wz haveged[447]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 18 08:53:15 volumio-wz haveged[447]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 18 08:53:15 volumio-wz haveged[447]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00056 Aug 18 08:53:15 volumio-wz haveged[447]: haveged: fills: 0, generated: 0 Aug 18 08:53:15 volumio-wz systemd[1]: Starting Hostname Service... Aug 18 08:53:15 volumio-wz systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 18 08:53:15 volumio-wz systemd[1]: Started Raise network interfaces. Aug 18 08:53:15 volumio-wz systemd[1]: Reached target Network. Aug 18 08:53:15 volumio-wz systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 18 08:53:15 volumio-wz systemd[1]: Started Checkmk agent - Asynchronous background tasks. Aug 18 08:53:15 volumio-wz systemd[1]: Starting Permit User Sessions... Aug 18 08:53:15 volumio-wz systemd[1]: Starting Network Time Service... Aug 18 08:53:15 volumio-wz systemd[1]: Starting OpenBSD Secure Shell server... Aug 18 08:53:15 volumio-wz systemd[1]: Reached target Network is Online. Aug 18 08:53:15 volumio-wz systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 18 08:53:15 volumio-wz systemd[1]: Starting LSB: Brings up/down network automatically... Aug 18 08:53:15 volumio-wz cpufrequtils[669]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 18 08:53:15 volumio-wz systemd[1]: Starting Samba NMB Daemon... Aug 18 08:53:15 volumio-wz systemd[1]: Starting /etc/rc.local Compatibility... Aug 18 08:53:16 volumio-wz systemd[1]: systemd-rfkill.service: Succeeded. Aug 18 08:53:16 volumio-wz dbus-daemon[492]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 18 08:53:16 volumio-wz systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 18 08:53:16 volumio-wz systemd[1]: Started Permit User Sessions. Aug 18 08:53:16 volumio-wz systemd[1]: Started /etc/rc.local Compatibility. Aug 18 08:53:16 volumio-wz systemd[1]: Started Hostname Service. Aug 18 08:53:16 volumio-wz systemd[1]: Starting Hold until boot process finishes up... Aug 18 08:53:16 volumio-wz ntpd[685]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 18 08:53:16 volumio-wz ntpd[685]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 18 08:53:16 volumio-wz systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 18 08:53:16 volumio-wz ntpd[715]: proto: precision = 1.146 usec (-20) Aug 18 08:53:16 volumio-wz systemd[1]: Started Network Time Service. Aug 18 08:53:16 volumio-wz ntpd[715]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 18 08:53:16 volumio-wz ntpd[715]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 18 08:53:16 volumio-wz ntpd[715]: Listen and drop on 0 v6wildcard [::]:123 Aug 18 08:53:16 volumio-wz ntpd[715]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 18 08:53:16 volumio-wz ntpd[715]: Listen normally on 2 lo 127.0.0.1:123 Aug 18 08:53:16 volumio-wz ntpd[715]: Listening on routing socket on fd #19 for interface updates Aug 18 08:53:16 volumio-wz ntpd[715]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 18 08:53:16 volumio-wz ntpd[715]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 18 08:53:16 volumio-wz systemd[1]: Received SIGRTMIN+21 from PID 180 (plymouthd). Aug 18 08:53:16 volumio-wz systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 18 08:53:16 volumio-wz systemd[1]: Started Hold until boot process finishes up. Aug 18 08:53:16 volumio-wz systemd[1]: plymouth-quit.service: Succeeded. Aug 18 08:53:16 volumio-wz systemd[1]: Started Terminate Plymouth Boot Screen. Aug 18 08:53:16 volumio-wz systemd[1]: Received SIGRTMIN+21 from PID 180 (n/a). Aug 18 08:53:16 volumio-wz systemd[1]: Started Serial Getty on ttyAMA0. Aug 18 08:53:16 volumio-wz ifplugd(eth0)[733]: ifplugd 0.28 initializing. Aug 18 08:53:16 volumio-wz ifplugd(eth0)[733]: Using interface eth0/B8:27:EB:48:46:85 with driver (version: 6.1.69-v7+) Aug 18 08:53:16 volumio-wz systemd[1]: Started Getty on tty1. Aug 18 08:53:16 volumio-wz systemd[1]: Reached target Login Prompts. Aug 18 08:53:16 volumio-wz ifplugd(eth0)[733]: Using detection mode: SIOCETHTOOL Aug 18 08:53:16 volumio-wz ifplugd(eth0)[733]: Initialization complete, link beat not detected. Aug 18 08:53:16 volumio-wz sshd[720]: Server listening on 0.0.0.0 port 22. Aug 18 08:53:16 volumio-wz sshd[720]: Server listening on :: port 22. Aug 18 08:53:16 volumio-wz systemd[1]: Started OpenBSD Secure Shell server. Aug 18 08:53:16 volumio-wz ifplugd[696]: Network Interface Plugging Daemon...start eth0...done. Aug 18 08:53:16 volumio-wz systemd[1]: Started LSB: Brings up/down network automatically. Aug 18 08:53:17 volumio-wz ntpd[715]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 18 08:53:17 volumio-wz nmbd[708]: [2024/08/18 08:53:17.765736, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 18 08:53:17 volumio-wz nmbd[708]: started asyncdns process 742 Aug 18 08:53:17 volumio-wz nmbd[708]: [2024/08/18 08:53:17.774010, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 18 08:53:17 volumio-wz nmbd[708]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 18 08:53:17 volumio-wz nmbd[708]: [2024/08/18 08:53:17.776941, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 18 08:53:17 volumio-wz nmbd[708]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 18 08:53:18 volumio-wz ntpd[715]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 18 08:53:19 volumio-wz ntpd[715]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 18 08:53:19 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:19] [info] asio async_connect error: system:111 (Connection refused) Aug 18 08:53:19 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 18 08:53:19 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:19] [error] handle_connect error: Underlying Transport Error Aug 18 08:53:20 volumio-wz ntpd[715]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 18 08:53:23 volumio-wz volumio[465]: info: ------------------------------------------- Aug 18 08:53:23 volumio-wz volumio[465]: info: ----- Volumio3 ---- Aug 18 08:53:23 volumio-wz volumio[465]: info: ------------------------------------------- Aug 18 08:53:23 volumio-wz volumio[465]: info: ----- System startup ---- Aug 18 08:53:23 volumio-wz volumio[465]: info: ------------------------------------------- Aug 18 08:53:25 volumio-wz volumio[465]: info: MYVOLUMIO Environment detected Aug 18 08:53:25 volumio-wz volumio[465]: info: Plugin folders cleanup Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning into folder /volumio/app/plugins/ Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category audio_interface Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category miscellanea Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category music_service Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category plugins.json Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category system_controller Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category user_interface Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning into folder /data/plugins/ Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category music_service Aug 18 08:53:25 volumio-wz volumio[465]: info: Scanning category user_interface Aug 18 08:53:25 volumio-wz volumio[465]: info: Plugin folders cleanup completed Aug 18 08:53:25 volumio-wz volumio[465]: info: ------------------------------------------- Aug 18 08:53:25 volumio-wz volumio[465]: info: ----- Core plugins startup ---- Aug 18 08:53:25 volumio-wz volumio[465]: info: ------------------------------------------- Aug 18 08:53:25 volumio-wz volumio[465]: info: Loading plugins from folder /volumio/app/plugins/ Aug 18 08:53:25 volumio-wz volumio[465]: info: Adding plugin upnp to MyMusic Plugins Aug 18 08:53:25 volumio-wz volumio[465]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 18 08:53:25 volumio-wz volumio[465]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 18 08:53:25 volumio-wz volumio[465]: info: Loading plugins from folder /data/plugins/ Aug 18 08:53:25 volumio-wz volumio[465]: info: Loading plugin "system"... Aug 18 08:53:26 volumio-wz volumio[465]: info: Loading plugin "appearance"... Aug 18 08:53:27 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:27] [connect] Successful connection Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "network"... Aug 18 08:53:29 volumio-wz volumio[465]: info: Refreshing Cached IP Addresses Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "services"... Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "alsa_controller"... Aug 18 08:53:29 volumio-wz sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:53:29 volumio-wz sudo[785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:53:29 volumio-wz sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:29 volumio-wz sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:29 volumio-wz sudo[785]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:29 volumio-wz sudo[787]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:29 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "wizard"... Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "networkfs"... Aug 18 08:53:29 volumio-wz volumio[465]: info: Starting Udev Watcher for removable devices Aug 18 08:53:29 volumio-wz sudo[797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro pandora.fufnet.de:/volume1/music /mnt/NAS/pandora Aug 18 08:53:29 volumio-wz sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:29 volumio-wz volumio[465]: info: Ignoring mount for partition: boot Aug 18 08:53:29 volumio-wz volumio[465]: info: Ignoring mount for partition: volumio Aug 18 08:53:29 volumio-wz volumio[465]: info: Ignoring mount for partition: volumio_data Aug 18 08:53:29 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "volumio_command_line_client"... Aug 18 08:53:29 volumio-wz sudo[797]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "upnp"... Aug 18 08:53:29 volumio-wz volumio[465]: info: [1723971209824] Starting Upmpd Daemon Aug 18 08:53:29 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "my_music"... Aug 18 08:53:29 volumio-wz volumio[465]: info: Loading plugin "mpd"... Aug 18 08:53:30 volumio-wz volumio[465]: info: Loading plugin "upnp_browser"... Aug 18 08:53:31 volumio-wz volumio[465]: info: Loading plugin "alarm-clock"... Aug 18 08:53:32 volumio-wz volumio[465]: info: Loading plugin "airplay_emulation"... Aug 18 08:53:32 volumio-wz volumio[465]: info: Starting Shairport Sync Aug 18 08:53:32 volumio-wz volumio[465]: info: Loading plugin "last_100"... Aug 18 08:53:32 volumio-wz volumio[465]: info: Loading plugin "webradio"... Aug 18 08:53:32 volumio-wz volumio[465]: info: Loading plugin "i2s_dacs"... Aug 18 08:53:32 volumio-wz volumio[465]: info: I2S DAC not set, start Auto-detection Aug 18 08:53:32 volumio-wz volumio[465]: info: Loading plugin "volumiodiscovery"... Aug 18 08:53:32 volumio-wz volumio[465]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:32 volumio-wz node[465]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:32 volumio-wz volumio[465]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:32 volumio-wz node[465]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:32 volumio-wz volumio[465]: *** WARNING *** For more information see Aug 18 08:53:32 volumio-wz node[465]: *** WARNING *** For more information see Aug 18 08:53:32 volumio-wz volumio[465]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:32 volumio-wz node[465]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:32 volumio-wz volumio[465]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:32 volumio-wz node[465]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:32 volumio-wz volumio[465]: *** WARNING *** For more information see Aug 18 08:53:32 volumio-wz node[465]: *** WARNING *** For more information see Aug 18 08:53:32 volumio-wz volumio[465]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 08:53:32 volumio-wz volumio[465]: info: Discovery: Started advertising with name: volumio-wz Aug 18 08:53:32 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:53:32 volumio-wz volumio[465]: info: Loading plugin "ytcr"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "outputs"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "albumart"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Plugin example_plugin is not enabled Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "inputs"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "updater_comm"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Plugin mpdemulation is not enabled Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "rest_api"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "websocket"... Aug 18 08:53:37 volumio-wz volumio[465]: info: Starting Socket.io Server version 2.3.0 Aug 18 08:53:37 volumio-wz volumio[465]: info: Loading plugin "80s80s"... Aug 18 08:53:38 volumio-wz volumio[465]: Forking 3 albumart workers Aug 18 08:53:38 volumio-wz volumio[465]: info: Applying required configuration parameters for plugin 80s80s Aug 18 08:53:38 volumio-wz volumio[465]: info: [1723971218851] [80s80s] API delay: 30 Aug 18 08:53:38 volumio-wz volumio[465]: info: Loading plugin "lastfm"... Aug 18 08:53:40 volumio-wz volumio[465]: Starting albumart workers Aug 18 08:53:40 volumio-wz volumio[465]: Starting albumart workers Aug 18 08:53:40 volumio-wz volumio[465]: Starting albumart workers Aug 18 08:53:40 volumio-wz volumio[465]: info: Loading i18n strings for locale de Aug 18 08:53:40 volumio-wz volumio[465]: Updating browse sources language Aug 18 08:53:40 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:53:41 volumio-wz systemd[1]: systemd-fsckd.service: Succeeded. Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::initPlayerControls Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:53:41 volumio-wz volumio[465]: Express server listening on port 3000 Aug 18 08:53:41 volumio-wz volumio[465]: [Metrics] WebUI: 20s 360.61ms Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreStateMachine::resetVolumioState Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreStateMachine::getcurrentVolume Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:53:41 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:53:41 volumio-wz sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:53:41 volumio-wz sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:41 volumio-wz sudo[835]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:41 volumio-wz sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:53:42 volumio-wz sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:42 volumio-wz sudo[837]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:42 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:42] [connect] Successful connection Aug 18 08:53:42 volumio-wz volumio[465]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:53:42 volumio-wz volumio[465]: info: Cannot mount NAS pandora at system boot, trial number 1 ,retrying in 5 seconds Aug 18 08:53:42 volumio-wz volumio[465]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 18 08:53:42 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723971222 101 Aug 18 08:53:42 volumio-wz volumio[465]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Aug 18 08:53:42 volumio-wz volumio[465]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreStateMachine::pushState Aug 18 08:53:42 volumio-wz volumio[465]: info: CorePlayQueue::getTrack 0 Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreCommandRouter::volumioPushState Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreStateMachine::updateTrackBlock Aug 18 08:53:42 volumio-wz volumio[465]: info: CorePlayQueue::getTrackBlock Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:53:42 volumio-wz volumio[465]: info: Reloading queue from file Aug 18 08:53:42 volumio-wz volumio[465]: info: Setting Device type: Raspberry PI Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreStateMachine::setRepeat null single undefined Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreStateMachine::pushState Aug 18 08:53:42 volumio-wz volumio[465]: info: CorePlayQueue::getTrack 0 Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreCommandRouter::volumioPushState Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreStateMachine::setRandom null Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreStateMachine::pushState Aug 18 08:53:42 volumio-wz volumio[465]: info: CorePlayQueue::getTrack 0 Aug 18 08:53:42 volumio-wz volumio[465]: info: CoreCommandRouter::volumioPushState Aug 18 08:53:42 volumio-wz volumio[465]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:53:43 volumio-wz sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 18 08:53:43 volumio-wz sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:43 volumio-wz volumio[465]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreStateMachine::pushState Aug 18 08:53:43 volumio-wz volumio[465]: info: CorePlayQueue::getTrack 0 Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::volumioPushState Aug 18 08:53:43 volumio-wz systemd[1]: Started UPnP Renderer front-end to MPD. Aug 18 08:53:43 volumio-wz sudo[874]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:43 volumio-wz volumio[465]: info: Completed loading Core Plugins Aug 18 08:53:43 volumio-wz volumio[465]: info: Preparing to generate the ALSA configuration file Aug 18 08:53:43 volumio-wz volumio[465]: info: Upmpdcli Daemon Started Aug 18 08:53:43 volumio-wz volumio[465]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 18 08:53:43 volumio-wz volumio[465]: info: Asound.conf file unchanged, so no further update is needed Aug 18 08:53:43 volumio-wz volumio[465]: info: Output device has changed, restarting MPD Aug 18 08:53:43 volumio-wz volumio[465]: info: Output device has changed, restarting Shairport Sync Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:43 volumio-wz sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:53:43 volumio-wz sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:43 volumio-wz sudo[878]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:43 volumio-wz sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:53:43 volumio-wz sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:43 volumio-wz volumio[465]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:53:43 volumio-wz volumio[465]: info: ___________ START PLUGINS ___________ Aug 18 08:53:43 volumio-wz volumio[465]: info: ControllerMpd::onStart: Initializing MPD Aug 18 08:53:43 volumio-wz volumio[465]: info: Creating MPD Configuration file Aug 18 08:53:43 volumio-wz systemd[1]: Listening on mpd.socket. Aug 18 08:53:43 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:53:43 volumio-wz volumio[465]: info: [1723971223701] CoreMusicLibrary::Adding element Medienserver Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:53:43 volumio-wz sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:53:43 volumio-wz sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:43 volumio-wz sudo[888]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:43 volumio-wz sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:53:43 volumio-wz sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:53:43 volumio-wz systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 18 08:53:43 volumio-wz systemd[1]: mpd.service: Succeeded. Aug 18 08:53:43 volumio-wz systemd[1]: Stopped Music Player Daemon. Aug 18 08:53:43 volumio-wz volumio[465]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:53:43 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:53:43 volumio-wz volumio[465]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:53:43 volumio-wz volumio[465]: info: [1723971223976] CoreMusicLibrary::Adding element Last_100 Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:53:43 volumio-wz volumio[465]: info: [1723971223985] CoreMusicLibrary::Adding element Webradio Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:53:43 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:53:44 volumio-wz volumio[465]: info: Initializing BBC Radios Aug 18 08:53:44 volumio-wz sudo[897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 08:53:44 volumio-wz sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:44 volumio-wz sudo[897]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 18 08:53:44 volumio-wz sudo[897]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:44 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:53:44 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:53:44 volumio-wz volumio[465]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:53:44 volumio-wz volumio[465]: info: [1723971224200] CoreMusicLibrary::Adding element 80s80s Radio Aug 18 08:53:44 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:53:44 volumio-wz volumio[465]: Cannot find translation for source 80s80s Radio Aug 18 08:53:44 volumio-wz volumio[465]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:53:44 volumio-wz volumio[465]: info: [1723971224214] CoreMusicLibrary::Adding element LastFM Aug 18 08:53:44 volumio-wz volumio[465]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:53:44 volumio-wz volumio[465]: Cannot find translation for source 80s80s Radio Aug 18 08:53:44 volumio-wz volumio[465]: Cannot find translation for source LastFM Aug 18 08:53:44 volumio-wz volumio[465]: info: [LastFM] scrobbler initiated! Aug 18 08:53:44 volumio-wz volumio[465]: info: [LastFM] extended logging: false Aug 18 08:53:44 volumio-wz volumio[465]: info: [LastFM] try scrobble stream/radio plays: true Aug 18 08:53:44 volumio-wz volumio[465]: info: [LastFM] Left init routine Aug 18 08:53:44 volumio-wz volumio[465]: info: [LastFM] Socket already connected: true Aug 18 08:53:44 volumio-wz volumio[465]: info: Volumio Calling Home Aug 18 08:53:44 volumio-wz sh[427]: timed out Aug 18 08:53:44 volumio-wz dhcpcd[502]: timed out Aug 18 08:53:44 volumio-wz sh[427]: dhcpcd exited Aug 18 08:53:44 volumio-wz dhcpcd[502]: dhcpcd exited Aug 18 08:53:44 volumio-wz sh[427]: ifup: failed to bring up eth0 Aug 18 08:53:44 volumio-wz systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 18 08:53:44 volumio-wz systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 18 08:53:44 volumio-wz volumio[465]: Unhandled rejection Error: No sockets available, cannot start. Aug 18 08:53:44 volumio-wz volumio[465]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 18 08:53:44 volumio-wz volumio[465]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 18 08:53:44 volumio-wz volumio[465]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 18 08:53:44 volumio-wz volumio[465]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 18 08:53:44 volumio-wz volumio[465]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 18 08:53:44 volumio-wz volumio[465]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 18 08:53:44 volumio-wz volumio[465]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 18 08:53:44 volumio-wz volumio[465]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 18 08:53:44 volumio-wz volumio[465]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 18 08:53:44 volumio-wz volumio[465]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 18 08:53:44 volumio-wz volumio[465]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 18 08:53:44 volumio-wz volumio[465]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 18 08:53:44 volumio-wz volumio[465]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 18 08:53:44 volumio-wz volumio[465]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 18 08:53:44 volumio-wz volumio[465]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 18 08:53:44 volumio-wz volumio[465]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 18 08:53:44 volumio-wz volumio[465]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:53:44 volumio-wz volumio[465]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Aug 18 08:53:44 volumio-wz volumio[465]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Aug 18 08:53:44 volumio-wz volumio[465]: errno: -16, Aug 18 08:53:44 volumio-wz volumio[465]: code: 'EBUSY', Aug 18 08:53:44 volumio-wz volumio[465]: syscall: 'getaddrinfo', Aug 18 08:53:44 volumio-wz volumio[465]: hostname: 'ws.audioscrobbler.com' Aug 18 08:53:44 volumio-wz volumio[465]: } Aug 18 08:53:44 volumio-wz volumio[465]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:53:46 volumio-wz systemd[1]: systemd-hostnamed.service: Succeeded. Aug 18 08:53:46 volumio-wz sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 08:52 Aug 18 08:53:46 volumio-wz sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:46 volumio-wz sudo[929]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:46 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 18 08:53:46 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 18 08:53:46 volumio-wz systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 18 08:53:46 volumio-wz sudo[880]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:46 volumio-wz sudo[891]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:46 volumio-wz systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 18 08:53:46 volumio-wz systemd[1]: Started dynamicswap service. Aug 18 08:53:46 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:53:46 volumio-wz systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 18 08:53:46 volumio-wz systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Aug 18 08:53:46 volumio-wz systemd[1]: Stopped Volumio Backend Module. Aug 18 08:53:46 volumio-wz systemd[1]: Started Volumio Backend Module. Aug 18 08:53:46 volumio-wz systemd[1]: Started dynamicswap service. Aug 18 08:53:47 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:53:50 volumio-wz mpd[913]: Aug 18 08:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 08:53:50 volumio-wz volumio[942]: info: ------------------------------------------- Aug 18 08:53:50 volumio-wz volumio[942]: info: ----- Volumio3 ---- Aug 18 08:53:50 volumio-wz volumio[942]: info: ------------------------------------------- Aug 18 08:53:50 volumio-wz volumio[942]: info: ----- System startup ---- Aug 18 08:53:50 volumio-wz volumio[942]: info: ------------------------------------------- Aug 18 08:53:50 volumio-wz systemd[1]: Started Music Player Daemon. Aug 18 08:53:51 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:53:51] [connect] Successful connection Aug 18 08:53:52 volumio-wz volumio[942]: info: MYVOLUMIO Environment detected Aug 18 08:53:52 volumio-wz volumio[942]: info: Plugin folders cleanup Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning into folder /volumio/app/plugins/ Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category audio_interface Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category miscellanea Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category music_service Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category plugins.json Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category system_controller Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category user_interface Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning into folder /data/plugins/ Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category music_service Aug 18 08:53:52 volumio-wz volumio[942]: info: Scanning category user_interface Aug 18 08:53:52 volumio-wz volumio[942]: info: Plugin folders cleanup completed Aug 18 08:53:52 volumio-wz volumio[942]: info: ------------------------------------------- Aug 18 08:53:52 volumio-wz volumio[942]: info: ----- Core plugins startup ---- Aug 18 08:53:52 volumio-wz volumio[942]: info: ------------------------------------------- Aug 18 08:53:52 volumio-wz volumio[942]: info: Loading plugins from folder /volumio/app/plugins/ Aug 18 08:53:52 volumio-wz volumio[942]: info: Adding plugin upnp to MyMusic Plugins Aug 18 08:53:52 volumio-wz volumio[942]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 18 08:53:52 volumio-wz volumio[942]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 18 08:53:52 volumio-wz volumio[942]: info: Loading plugins from folder /data/plugins/ Aug 18 08:53:52 volumio-wz volumio[942]: info: Loading plugin "system"... Aug 18 08:53:52 volumio-wz volumio[942]: info: Loading plugin "appearance"... Aug 18 08:53:54 volumio-wz volumio[942]: info: Loading plugin "network"... Aug 18 08:53:54 volumio-wz volumio[942]: info: Refreshing Cached IP Addresses Aug 18 08:53:54 volumio-wz volumio[942]: info: Loading plugin "services"... Aug 18 08:53:54 volumio-wz sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:53:54 volumio-wz sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:54 volumio-wz volumio[942]: info: Loading plugin "alsa_controller"... Aug 18 08:53:54 volumio-wz sudo[971]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:54 volumio-wz sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:53:54 volumio-wz sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:54 volumio-wz sudo[973]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:55 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:53:55 volumio-wz volumio[942]: info: Loading plugin "wizard"... Aug 18 08:53:55 volumio-wz volumio[942]: info: Loading plugin "networkfs"... Aug 18 08:53:55 volumio-wz volumio[942]: info: Starting Udev Watcher for removable devices Aug 18 08:53:55 volumio-wz sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro pandora.fufnet.de:/volume1/music /mnt/NAS/pandora Aug 18 08:53:55 volumio-wz sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:53:55 volumio-wz sudo[983]: pam_unix(sudo:session): session closed for user root Aug 18 08:53:55 volumio-wz volumio[942]: info: Ignoring mount for partition: boot Aug 18 08:53:55 volumio-wz volumio[942]: info: Ignoring mount for partition: volumio Aug 18 08:53:55 volumio-wz volumio[942]: info: Ignoring mount for partition: volumio_data Aug 18 08:53:55 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:53:55 volumio-wz volumio[942]: info: Loading plugin "volumio_command_line_client"... Aug 18 08:53:55 volumio-wz volumio[942]: info: Loading plugin "upnp"... Aug 18 08:53:55 volumio-wz volumio[942]: info: [1723971235285] Starting Upmpd Daemon Aug 18 08:53:55 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:53:55 volumio-wz volumio[942]: info: Loading plugin "my_music"... Aug 18 08:53:55 volumio-wz volumio[942]: info: Loading plugin "mpd"... Aug 18 08:53:56 volumio-wz volumio[942]: info: Loading plugin "upnp_browser"... Aug 18 08:53:56 volumio-wz volumio[942]: info: Loading plugin "alarm-clock"... Aug 18 08:53:56 volumio-wz volumio[942]: info: Loading plugin "airplay_emulation"... Aug 18 08:53:56 volumio-wz volumio[942]: info: Starting Shairport Sync Aug 18 08:53:56 volumio-wz volumio[942]: info: Loading plugin "last_100"... Aug 18 08:53:56 volumio-wz volumio[942]: info: Loading plugin "webradio"... Aug 18 08:53:57 volumio-wz volumio[942]: info: Loading plugin "i2s_dacs"... Aug 18 08:53:57 volumio-wz volumio[942]: info: I2S DAC not set, start Auto-detection Aug 18 08:53:57 volumio-wz volumio[942]: info: Loading plugin "volumiodiscovery"... Aug 18 08:53:57 volumio-wz volumio[942]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:57 volumio-wz volumio[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:57 volumio-wz node[942]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:57 volumio-wz volumio[942]: *** WARNING *** For more information see Aug 18 08:53:57 volumio-wz volumio[942]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:57 volumio-wz volumio[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:57 volumio-wz volumio[942]: *** WARNING *** For more information see Aug 18 08:53:57 volumio-wz node[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:57 volumio-wz node[942]: *** WARNING *** For more information see Aug 18 08:53:57 volumio-wz node[942]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:53:57 volumio-wz node[942]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:53:57 volumio-wz node[942]: *** WARNING *** For more information see Aug 18 08:53:57 volumio-wz volumio[942]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 08:53:57 volumio-wz volumio[942]: info: Discovery: Started advertising with name: volumio-wz Aug 18 08:53:57 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:53:57 volumio-wz volumio[942]: info: Loading plugin "ytcr"... Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "outputs"... Aug 18 08:54:00 volumio-wz systemd[1]: Started Checkmk agent updater. Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "albumart"... Aug 18 08:54:00 volumio-wz volumio[942]: info: Plugin example_plugin is not enabled Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "inputs"... Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "updater_comm"... Aug 18 08:54:00 volumio-wz volumio[942]: info: Plugin mpdemulation is not enabled Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "rest_api"... Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "websocket"... Aug 18 08:54:00 volumio-wz volumio[942]: info: Starting Socket.io Server version 2.3.0 Aug 18 08:54:00 volumio-wz volumio[942]: info: Loading plugin "80s80s"... Aug 18 08:54:01 volumio-wz volumio[942]: Forking 3 albumart workers Aug 18 08:54:01 volumio-wz volumio[942]: info: Applying required configuration parameters for plugin 80s80s Aug 18 08:54:01 volumio-wz volumio[942]: info: [1723971241317] [80s80s] API delay: 30 Aug 18 08:54:01 volumio-wz volumio[942]: info: Loading plugin "lastfm"... Aug 18 08:54:03 volumio-wz volumio[942]: info: Loading i18n strings for locale de Aug 18 08:54:03 volumio-wz volumio[942]: Updating browse sources language Aug 18 08:54:03 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::initPlayerControls Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: Express server listening on port 3000 Aug 18 08:54:04 volumio-wz volumio[942]: [Metrics] WebUI: 14s 480.87ms Aug 18 08:54:04 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:04] [connect] Successful connection Aug 18 08:54:04 volumio-wz volumio[942]: Starting albumart workers Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreStateMachine::resetVolumioState Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreStateMachine::getcurrentVolume Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:54:04 volumio-wz volumio[942]: Starting albumart workers Aug 18 08:54:04 volumio-wz volumio[942]: Starting albumart workers Aug 18 08:54:04 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:04 volumio-wz volumio[942]: info: Cannot mount NAS pandora at system boot, trial number 1 ,retrying in 5 seconds Aug 18 08:54:04 volumio-wz systemd[1]: cmk-update-agent.service: Succeeded. Aug 18 08:54:04 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723971244 101 Aug 18 08:54:04 volumio-wz volumio[942]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 18 08:54:04 volumio-wz volumio[942]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:54:04 volumio-wz volumio[942]: Upnp client error: Error: This socket has been ended by the other party Aug 18 08:54:04 volumio-wz volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 18 08:54:05 volumio-wz volumio[942]: info: Reloading queue from file Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::setRepeat null single undefined Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::pushState Aug 18 08:54:05 volumio-wz volumio[942]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::setRandom null Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::pushState Aug 18 08:54:05 volumio-wz volumio[942]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:05 volumio-wz volumio[942]: info: Setting Device type: Raspberry PI Aug 18 08:54:05 volumio-wz volumio[942]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::pushState Aug 18 08:54:05 volumio-wz volumio[942]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::updateTrackBlock Aug 18 08:54:05 volumio-wz volumio[942]: info: CorePlayQueue::getTrackBlock Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:54:05 volumio-wz volumio[942]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:54:05 volumio-wz sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:54:05 volumio-wz sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:05 volumio-wz sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:54:05 volumio-wz sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:05 volumio-wz sudo[1030]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:05 volumio-wz sudo[1032]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:05 volumio-wz volumio[942]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreStateMachine::pushState Aug 18 08:54:05 volumio-wz volumio[942]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:05 volumio-wz volumio[942]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:05 volumio-wz volumio[942]: info: Completed loading Core Plugins Aug 18 08:54:05 volumio-wz volumio[942]: info: Preparing to generate the ALSA configuration file Aug 18 08:54:06 volumio-wz volumio[942]: info: Asound.conf file unchanged, so no further update is needed Aug 18 08:54:06 volumio-wz volumio[942]: info: Output device has changed, restarting MPD Aug 18 08:54:06 volumio-wz sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 18 08:54:06 volumio-wz sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:06 volumio-wz volumio[942]: info: Output device has changed, restarting Shairport Sync Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:06 volumio-wz sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:54:06 volumio-wz sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:06 volumio-wz sudo[1049]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:06 volumio-wz sudo[1047]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:06 volumio-wz sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:54:06 volumio-wz sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:06 volumio-wz volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:06 volumio-wz volumio[942]: info: ___________ START PLUGINS ___________ Aug 18 08:54:06 volumio-wz systemd[1]: Stopping Music Player Daemon... Aug 18 08:54:06 volumio-wz volumio[942]: info: ControllerMpd::onStart: Initializing MPD Aug 18 08:54:06 volumio-wz volumio[942]: info: Creating MPD Configuration file Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:06 volumio-wz volumio[942]: info: [1723971246515] CoreMusicLibrary::Adding element Medienserver Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:06 volumio-wz sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:54:06 volumio-wz sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:06 volumio-wz sudo[1067]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:06 volumio-wz sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:54:06 volumio-wz sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:06 volumio-wz volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:06 volumio-wz volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:06 volumio-wz volumio[942]: info: [1723971246831] CoreMusicLibrary::Adding element Last_100 Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:06 volumio-wz systemd[1]: mpd.service: Succeeded. Aug 18 08:54:06 volumio-wz systemd[1]: Stopped Music Player Daemon. Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:06 volumio-wz volumio[942]: info: [1723971246848] CoreMusicLibrary::Adding element Webradio Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:06 volumio-wz volumio[942]: info: Initializing BBC Radios Aug 18 08:54:06 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:54:06 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:07 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:07 volumio-wz volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:07 volumio-wz volumio[942]: info: [1723971247039] CoreMusicLibrary::Adding element 80s80s Radio Aug 18 08:54:07 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:07 volumio-wz volumio[942]: Cannot find translation for source 80s80s Radio Aug 18 08:54:07 volumio-wz volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:07 volumio-wz volumio[942]: info: [1723971247049] CoreMusicLibrary::Adding element LastFM Aug 18 08:54:07 volumio-wz volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:07 volumio-wz volumio[942]: Cannot find translation for source 80s80s Radio Aug 18 08:54:07 volumio-wz volumio[942]: Cannot find translation for source LastFM Aug 18 08:54:07 volumio-wz volumio[942]: info: [LastFM] scrobbler initiated! Aug 18 08:54:07 volumio-wz volumio[942]: info: [LastFM] extended logging: false Aug 18 08:54:07 volumio-wz volumio[942]: info: [LastFM] try scrobble stream/radio plays: true Aug 18 08:54:07 volumio-wz volumio[942]: info: [LastFM] Left init routine Aug 18 08:54:07 volumio-wz volumio[942]: info: [LastFM] Socket already connected: true Aug 18 08:54:07 volumio-wz volumio[942]: info: Volumio Calling Home Aug 18 08:54:07 volumio-wz sudo[1086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 08:54:07 volumio-wz sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:07 volumio-wz sudo[1086]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:07 volumio-wz volumio[942]: Unhandled rejection Error: No sockets available, cannot start. Aug 18 08:54:07 volumio-wz volumio[942]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 18 08:54:07 volumio-wz volumio[942]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 18 08:54:07 volumio-wz volumio[942]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 18 08:54:07 volumio-wz volumio[942]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 18 08:54:07 volumio-wz volumio[942]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 18 08:54:07 volumio-wz volumio[942]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 18 08:54:07 volumio-wz volumio[942]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 18 08:54:07 volumio-wz volumio[942]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 18 08:54:07 volumio-wz volumio[942]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 18 08:54:07 volumio-wz volumio[942]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 18 08:54:07 volumio-wz volumio[942]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 18 08:54:07 volumio-wz volumio[942]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 18 08:54:07 volumio-wz volumio[942]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 18 08:54:07 volumio-wz volumio[942]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 18 08:54:07 volumio-wz volumio[942]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 18 08:54:07 volumio-wz volumio[942]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 18 08:54:07 volumio-wz volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:54:07 volumio-wz volumio[942]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Aug 18 08:54:07 volumio-wz volumio[942]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Aug 18 08:54:07 volumio-wz volumio[942]: errno: -16, Aug 18 08:54:07 volumio-wz volumio[942]: code: 'EBUSY', Aug 18 08:54:07 volumio-wz volumio[942]: syscall: 'getaddrinfo', Aug 18 08:54:07 volumio-wz volumio[942]: hostname: 'ws.audioscrobbler.com' Aug 18 08:54:07 volumio-wz volumio[942]: } Aug 18 08:54:07 volumio-wz volumio[942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:54:08 volumio-wz sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 08:53 Aug 18 08:54:08 volumio-wz sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:09 volumio-wz sudo[1113]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:09 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 18 08:54:09 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 18 08:54:09 volumio-wz systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 18 08:54:09 volumio-wz sudo[1073]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:09 volumio-wz sudo[1056]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:09 volumio-wz systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 18 08:54:09 volumio-wz systemd[1]: Started dynamicswap service. Aug 18 08:54:09 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:54:09 volumio-wz systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 18 08:54:09 volumio-wz systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 18 08:54:09 volumio-wz systemd[1]: Stopped Volumio Backend Module. Aug 18 08:54:09 volumio-wz systemd[1]: Started Volumio Backend Module. Aug 18 08:54:09 volumio-wz systemd[1]: Started dynamicswap service. Aug 18 08:54:09 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:54:09 volumio-wz mpd[1102]: Aug 18 08:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 08:54:10 volumio-wz systemd[1]: Started Music Player Daemon. Aug 18 08:54:13 volumio-wz volumio[1126]: info: ------------------------------------------- Aug 18 08:54:13 volumio-wz volumio[1126]: info: ----- Volumio3 ---- Aug 18 08:54:13 volumio-wz volumio[1126]: info: ------------------------------------------- Aug 18 08:54:13 volumio-wz volumio[1126]: info: ----- System startup ---- Aug 18 08:54:13 volumio-wz volumio[1126]: info: ------------------------------------------- Aug 18 08:54:14 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:14] [connect] Successful connection Aug 18 08:54:14 volumio-wz volumio[1126]: info: MYVOLUMIO Environment detected Aug 18 08:54:14 volumio-wz volumio[1126]: info: Plugin folders cleanup Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning into folder /volumio/app/plugins/ Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category audio_interface Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category miscellanea Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category music_service Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category plugins.json Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category system_controller Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category user_interface Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning into folder /data/plugins/ Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category music_service Aug 18 08:54:14 volumio-wz volumio[1126]: info: Scanning category user_interface Aug 18 08:54:14 volumio-wz volumio[1126]: info: Plugin folders cleanup completed Aug 18 08:54:14 volumio-wz volumio[1126]: info: ------------------------------------------- Aug 18 08:54:14 volumio-wz volumio[1126]: info: ----- Core plugins startup ---- Aug 18 08:54:14 volumio-wz volumio[1126]: info: ------------------------------------------- Aug 18 08:54:14 volumio-wz volumio[1126]: info: Loading plugins from folder /volumio/app/plugins/ Aug 18 08:54:14 volumio-wz volumio[1126]: info: Adding plugin upnp to MyMusic Plugins Aug 18 08:54:14 volumio-wz volumio[1126]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 18 08:54:14 volumio-wz volumio[1126]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 18 08:54:14 volumio-wz volumio[1126]: info: Loading plugins from folder /data/plugins/ Aug 18 08:54:14 volumio-wz volumio[1126]: info: Loading plugin "system"... Aug 18 08:54:14 volumio-wz volumio[1126]: info: Loading plugin "appearance"... Aug 18 08:54:16 volumio-wz volumio[1126]: info: Loading plugin "network"... Aug 18 08:54:16 volumio-wz volumio[1126]: info: Refreshing Cached IP Addresses Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "services"... Aug 18 08:54:17 volumio-wz sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:54:17 volumio-wz sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "alsa_controller"... Aug 18 08:54:17 volumio-wz sudo[1178]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:17 volumio-wz sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:54:17 volumio-wz sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:17 volumio-wz sudo[1180]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:17 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "wizard"... Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "networkfs"... Aug 18 08:54:17 volumio-wz volumio[1126]: info: Starting Udev Watcher for removable devices Aug 18 08:54:17 volumio-wz sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro pandora.fufnet.de:/volume1/music /mnt/NAS/pandora Aug 18 08:54:17 volumio-wz sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:17 volumio-wz sudo[1190]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:17 volumio-wz volumio[1126]: info: Ignoring mount for partition: boot Aug 18 08:54:17 volumio-wz volumio[1126]: info: Ignoring mount for partition: volumio Aug 18 08:54:17 volumio-wz volumio[1126]: info: Ignoring mount for partition: volumio_data Aug 18 08:54:17 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "volumio_command_line_client"... Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "upnp"... Aug 18 08:54:17 volumio-wz volumio[1126]: info: [1723971257511] Starting Upmpd Daemon Aug 18 08:54:17 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "my_music"... Aug 18 08:54:17 volumio-wz volumio[1126]: info: Loading plugin "mpd"... Aug 18 08:54:18 volumio-wz volumio[1126]: info: Loading plugin "upnp_browser"... Aug 18 08:54:18 volumio-wz volumio[1126]: info: Loading plugin "alarm-clock"... Aug 18 08:54:19 volumio-wz volumio[1126]: info: Loading plugin "airplay_emulation"... Aug 18 08:54:19 volumio-wz volumio[1126]: info: Starting Shairport Sync Aug 18 08:54:19 volumio-wz volumio[1126]: info: Loading plugin "last_100"... Aug 18 08:54:19 volumio-wz volumio[1126]: info: Loading plugin "webradio"... Aug 18 08:54:19 volumio-wz volumio[1126]: info: Loading plugin "i2s_dacs"... Aug 18 08:54:19 volumio-wz volumio[1126]: info: I2S DAC not set, start Auto-detection Aug 18 08:54:19 volumio-wz volumio[1126]: info: Loading plugin "volumiodiscovery"... Aug 18 08:54:19 volumio-wz volumio[1126]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:19 volumio-wz volumio[1126]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:19 volumio-wz volumio[1126]: *** WARNING *** For more information see Aug 18 08:54:19 volumio-wz node[1126]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:19 volumio-wz volumio[1126]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:19 volumio-wz volumio[1126]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:19 volumio-wz volumio[1126]: *** WARNING *** For more information see Aug 18 08:54:19 volumio-wz node[1126]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:19 volumio-wz node[1126]: *** WARNING *** For more information see Aug 18 08:54:19 volumio-wz node[1126]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:19 volumio-wz node[1126]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:19 volumio-wz node[1126]: *** WARNING *** For more information see Aug 18 08:54:19 volumio-wz volumio[1126]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 08:54:19 volumio-wz volumio[1126]: info: Discovery: Started advertising with name: volumio-wz Aug 18 08:54:19 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:54:19 volumio-wz volumio[1126]: info: Loading plugin "ytcr"... Aug 18 08:54:22 volumio-wz ntpd[715]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "outputs"... Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "albumart"... Aug 18 08:54:22 volumio-wz volumio[1126]: info: Plugin example_plugin is not enabled Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "inputs"... Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "updater_comm"... Aug 18 08:54:22 volumio-wz volumio[1126]: info: Plugin mpdemulation is not enabled Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "rest_api"... Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "websocket"... Aug 18 08:54:22 volumio-wz volumio[1126]: info: Starting Socket.io Server version 2.3.0 Aug 18 08:54:22 volumio-wz volumio[1126]: info: Loading plugin "80s80s"... Aug 18 08:54:23 volumio-wz volumio[1126]: Forking 3 albumart workers Aug 18 08:54:23 volumio-wz volumio[1126]: info: Applying required configuration parameters for plugin 80s80s Aug 18 08:54:23 volumio-wz volumio[1126]: info: [1723971263541] [80s80s] API delay: 30 Aug 18 08:54:23 volumio-wz volumio[1126]: info: Loading plugin "lastfm"... Aug 18 08:54:24 volumio-wz ntpd[715]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 18 08:54:24 volumio-wz volumio[1126]: info: Loading i18n strings for locale de Aug 18 08:54:24 volumio-wz volumio[1126]: Updating browse sources language Aug 18 08:54:24 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:25 volumio-wz ntpd[715]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::initPlayerControls Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: Express server listening on port 3000 Aug 18 08:54:25 volumio-wz volumio[1126]: [Metrics] WebUI: 13s 384.49ms Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreStateMachine::resetVolumioState Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreStateMachine::getcurrentVolume Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:54:25 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:25 volumio-wz volumio[1126]: Starting albumart workers Aug 18 08:54:25 volumio-wz volumio[1126]: Starting albumart workers Aug 18 08:54:25 volumio-wz volumio[1126]: info: Cannot mount NAS pandora at system boot, trial number 1 ,retrying in 5 seconds Aug 18 08:54:25 volumio-wz volumio[1126]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:54:26 volumio-wz volumio[1126]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 18 08:54:26 volumio-wz volumio[1126]: Starting albumart workers Aug 18 08:54:26 volumio-wz volumio[1126]: info: Reloading queue from file Aug 18 08:54:26 volumio-wz ntpd[715]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 18 08:54:26 volumio-wz volumio[1126]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::pushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::updateTrackBlock Aug 18 08:54:26 volumio-wz volumio[1126]: info: CorePlayQueue::getTrackBlock Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::setRepeat null single undefined Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::pushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::setRandom null Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::pushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: Setting Device type: Raspberry PI Aug 18 08:54:26 volumio-wz volumio[1126]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:54:26 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:26] [connect] Successful connection Aug 18 08:54:26 volumio-wz volumio[1126]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreStateMachine::pushState Aug 18 08:54:26 volumio-wz volumio[1126]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:26 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:27 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723971266 101 Aug 18 08:54:27 volumio-wz volumio[1126]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Aug 18 08:54:27 volumio-wz volumio[1126]: info: Completed loading Core Plugins Aug 18 08:54:27 volumio-wz volumio[1126]: info: Preparing to generate the ALSA configuration file Aug 18 08:54:27 volumio-wz volumio[1126]: info: Asound.conf file unchanged, so no further update is needed Aug 18 08:54:27 volumio-wz volumio[1126]: info: Output device has changed, restarting MPD Aug 18 08:54:27 volumio-wz volumio[1126]: info: Output device has changed, restarting Shairport Sync Aug 18 08:54:27 volumio-wz sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:27 volumio-wz sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:27 volumio-wz sudo[1293]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:27 volumio-wz sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:54:27 volumio-wz sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:27 volumio-wz systemd[1]: Stopping Music Player Daemon... Aug 18 08:54:27 volumio-wz volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:27 volumio-wz volumio[1126]: info: ___________ START PLUGINS ___________ Aug 18 08:54:27 volumio-wz volumio[1126]: info: ControllerMpd::onStart: Initializing MPD Aug 18 08:54:27 volumio-wz volumio[1126]: info: Creating MPD Configuration file Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:27 volumio-wz volumio[1126]: info: [1723971267570] CoreMusicLibrary::Adding element Medienserver Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:27 volumio-wz sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:54:27 volumio-wz sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:27 volumio-wz systemd[1]: mpd.service: Succeeded. Aug 18 08:54:27 volumio-wz sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:54:27 volumio-wz systemd[1]: Stopped Music Player Daemon. Aug 18 08:54:27 volumio-wz sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:27 volumio-wz sudo[1302]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:27 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:54:27 volumio-wz volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:27 volumio-wz systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 18 08:54:27 volumio-wz systemd[1]: mpd.service: Succeeded. Aug 18 08:54:27 volumio-wz systemd[1]: Stopped Music Player Daemon. Aug 18 08:54:27 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:54:27 volumio-wz volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:27 volumio-wz volumio[1126]: info: [1723971267883] CoreMusicLibrary::Adding element Last_100 Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:27 volumio-wz volumio[1126]: info: [1723971267892] CoreMusicLibrary::Adding element Webradio Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:27 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:27 volumio-wz volumio[1126]: info: Initializing BBC Radios Aug 18 08:54:28 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:28 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:28 volumio-wz sudo[1321]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 08:54:28 volumio-wz sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:28 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:28 volumio-wz volumio[1126]: info: [1723971268080] CoreMusicLibrary::Adding element 80s80s Radio Aug 18 08:54:28 volumio-wz sudo[1321]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:28 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:28 volumio-wz volumio[1126]: Cannot find translation for source 80s80s Radio Aug 18 08:54:28 volumio-wz volumio[1126]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:28 volumio-wz volumio[1126]: info: [1723971268091] CoreMusicLibrary::Adding element LastFM Aug 18 08:54:28 volumio-wz volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:28 volumio-wz volumio[1126]: Cannot find translation for source 80s80s Radio Aug 18 08:54:28 volumio-wz volumio[1126]: Cannot find translation for source LastFM Aug 18 08:54:28 volumio-wz volumio[1126]: info: [LastFM] scrobbler initiated! Aug 18 08:54:28 volumio-wz volumio[1126]: info: [LastFM] extended logging: false Aug 18 08:54:28 volumio-wz volumio[1126]: info: [LastFM] try scrobble stream/radio plays: true Aug 18 08:54:28 volumio-wz volumio[1126]: info: [LastFM] Left init routine Aug 18 08:54:28 volumio-wz volumio[1126]: info: [LastFM] Socket already connected: true Aug 18 08:54:28 volumio-wz volumio[1126]: info: Volumio Calling Home Aug 18 08:54:28 volumio-wz volumio[1126]: Unhandled rejection Error: No sockets available, cannot start. Aug 18 08:54:28 volumio-wz volumio[1126]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 18 08:54:28 volumio-wz volumio[1126]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 18 08:54:28 volumio-wz volumio[1126]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 18 08:54:28 volumio-wz volumio[1126]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 18 08:54:28 volumio-wz volumio[1126]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 18 08:54:28 volumio-wz volumio[1126]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 18 08:54:28 volumio-wz volumio[1126]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 18 08:54:28 volumio-wz volumio[1126]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 18 08:54:28 volumio-wz volumio[1126]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 18 08:54:28 volumio-wz volumio[1126]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 18 08:54:28 volumio-wz volumio[1126]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 18 08:54:28 volumio-wz volumio[1126]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 18 08:54:28 volumio-wz volumio[1126]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 18 08:54:28 volumio-wz volumio[1126]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 18 08:54:28 volumio-wz volumio[1126]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 18 08:54:28 volumio-wz volumio[1126]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 18 08:54:28 volumio-wz volumio[1126]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:54:28 volumio-wz sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:54:28 volumio-wz sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:28 volumio-wz sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:54:28 volumio-wz sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:28 volumio-wz sudo[1340]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:28 volumio-wz volumio[1126]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Aug 18 08:54:28 volumio-wz volumio[1126]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Aug 18 08:54:28 volumio-wz volumio[1126]: errno: -16, Aug 18 08:54:28 volumio-wz volumio[1126]: code: 'EBUSY', Aug 18 08:54:28 volumio-wz volumio[1126]: syscall: 'getaddrinfo', Aug 18 08:54:28 volumio-wz volumio[1126]: hostname: 'ws.audioscrobbler.com' Aug 18 08:54:28 volumio-wz volumio[1126]: } Aug 18 08:54:28 volumio-wz volumio[1126]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:54:28 volumio-wz sudo[1342]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:29 volumio-wz sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 08:53 Aug 18 08:54:29 volumio-wz sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:30 volumio-wz sudo[1356]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:30 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 18 08:54:30 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 18 08:54:30 volumio-wz systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 18 08:54:30 volumio-wz sudo[1296]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:30 volumio-wz sudo[1304]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:30 volumio-wz systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 18 08:54:30 volumio-wz systemd[1]: Started dynamicswap service. Aug 18 08:54:30 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:54:30 volumio-wz systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 18 08:54:30 volumio-wz systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Aug 18 08:54:30 volumio-wz systemd[1]: Started dynamicswap service. Aug 18 08:54:30 volumio-wz systemd[1]: Stopped Volumio Backend Module. Aug 18 08:54:30 volumio-wz systemd[1]: Started Volumio Backend Module. Aug 18 08:54:30 volumio-wz systemd[1]: dynamicswap.service: Succeeded. Aug 18 08:54:30 volumio-wz mpd[1335]: Aug 18 08:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 08:54:31 volumio-wz systemd[1]: Started Music Player Daemon. Aug 18 08:54:34 volumio-wz volumio[1370]: info: ------------------------------------------- Aug 18 08:54:34 volumio-wz volumio[1370]: info: ----- Volumio3 ---- Aug 18 08:54:34 volumio-wz volumio[1370]: info: ------------------------------------------- Aug 18 08:54:34 volumio-wz volumio[1370]: info: ----- System startup ---- Aug 18 08:54:34 volumio-wz volumio[1370]: info: ------------------------------------------- Aug 18 08:54:35 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:35] [connect] Successful connection Aug 18 08:54:35 volumio-wz volumio[1370]: info: MYVOLUMIO Environment detected Aug 18 08:54:35 volumio-wz volumio[1370]: info: Plugin folders cleanup Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning into folder /volumio/app/plugins/ Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category audio_interface Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category miscellanea Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category music_service Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category plugins.json Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category system_controller Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category user_interface Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning into folder /data/plugins/ Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category music_service Aug 18 08:54:35 volumio-wz volumio[1370]: info: Scanning category user_interface Aug 18 08:54:35 volumio-wz volumio[1370]: info: Plugin folders cleanup completed Aug 18 08:54:35 volumio-wz volumio[1370]: info: ------------------------------------------- Aug 18 08:54:35 volumio-wz volumio[1370]: info: ----- Core plugins startup ---- Aug 18 08:54:35 volumio-wz volumio[1370]: info: ------------------------------------------- Aug 18 08:54:35 volumio-wz volumio[1370]: info: Loading plugins from folder /volumio/app/plugins/ Aug 18 08:54:35 volumio-wz volumio[1370]: info: Adding plugin upnp to MyMusic Plugins Aug 18 08:54:35 volumio-wz volumio[1370]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 18 08:54:35 volumio-wz volumio[1370]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 18 08:54:35 volumio-wz volumio[1370]: info: Loading plugins from folder /data/plugins/ Aug 18 08:54:35 volumio-wz volumio[1370]: info: Loading plugin "system"... Aug 18 08:54:35 volumio-wz volumio[1370]: info: Loading plugin "appearance"... Aug 18 08:54:37 volumio-wz volumio[1370]: info: Loading plugin "network"... Aug 18 08:54:37 volumio-wz volumio[1370]: info: Refreshing Cached IP Addresses Aug 18 08:54:37 volumio-wz volumio[1370]: info: Loading plugin "services"... Aug 18 08:54:37 volumio-wz volumio[1370]: info: Loading plugin "alsa_controller"... Aug 18 08:54:37 volumio-wz sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:54:37 volumio-wz sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:37 volumio-wz sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:54:37 volumio-wz sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:37 volumio-wz sudo[1398]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:37 volumio-wz sudo[1400]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:38 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "wizard"... Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "networkfs"... Aug 18 08:54:38 volumio-wz volumio[1370]: info: Starting Udev Watcher for removable devices Aug 18 08:54:38 volumio-wz sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro pandora.fufnet.de:/volume1/music /mnt/NAS/pandora Aug 18 08:54:38 volumio-wz sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:38 volumio-wz volumio[1370]: info: Ignoring mount for partition: boot Aug 18 08:54:38 volumio-wz volumio[1370]: info: Ignoring mount for partition: volumio Aug 18 08:54:38 volumio-wz volumio[1370]: info: Ignoring mount for partition: volumio_data Aug 18 08:54:38 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "volumio_command_line_client"... Aug 18 08:54:38 volumio-wz sudo[1410]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "upnp"... Aug 18 08:54:38 volumio-wz volumio[1370]: info: [1723971278234] Starting Upmpd Daemon Aug 18 08:54:38 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "my_music"... Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "mpd"... Aug 18 08:54:38 volumio-wz volumio[1370]: info: Loading plugin "upnp_browser"... Aug 18 08:54:39 volumio-wz volumio[1370]: info: Loading plugin "alarm-clock"... Aug 18 08:54:39 volumio-wz volumio[1370]: info: Loading plugin "airplay_emulation"... Aug 18 08:54:39 volumio-wz volumio[1370]: info: Starting Shairport Sync Aug 18 08:54:39 volumio-wz volumio[1370]: info: Loading plugin "last_100"... Aug 18 08:54:39 volumio-wz volumio[1370]: info: Loading plugin "webradio"... Aug 18 08:54:40 volumio-wz volumio[1370]: info: Loading plugin "i2s_dacs"... Aug 18 08:54:40 volumio-wz volumio[1370]: info: I2S DAC not set, start Auto-detection Aug 18 08:54:40 volumio-wz volumio[1370]: info: Loading plugin "volumiodiscovery"... Aug 18 08:54:40 volumio-wz volumio[1370]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:40 volumio-wz volumio[1370]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:40 volumio-wz volumio[1370]: *** WARNING *** For more information see Aug 18 08:54:40 volumio-wz node[1370]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:40 volumio-wz volumio[1370]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:40 volumio-wz volumio[1370]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:40 volumio-wz volumio[1370]: *** WARNING *** For more information see Aug 18 08:54:40 volumio-wz node[1370]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:40 volumio-wz node[1370]: *** WARNING *** For more information see Aug 18 08:54:40 volumio-wz node[1370]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 08:54:40 volumio-wz node[1370]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 08:54:40 volumio-wz node[1370]: *** WARNING *** For more information see Aug 18 08:54:40 volumio-wz volumio[1370]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 08:54:40 volumio-wz volumio[1370]: info: Discovery: Started advertising with name: volumio-wz Aug 18 08:54:40 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 08:54:40 volumio-wz volumio[1370]: info: Loading plugin "ytcr"... Aug 18 08:54:42 volumio-wz volumio[1370]: info: Loading plugin "outputs"... Aug 18 08:54:42 volumio-wz volumio[1370]: info: Loading plugin "albumart"... Aug 18 08:54:43 volumio-wz volumio[1370]: info: Plugin example_plugin is not enabled Aug 18 08:54:43 volumio-wz volumio[1370]: info: Loading plugin "inputs"... Aug 18 08:54:43 volumio-wz volumio[1370]: info: Loading plugin "updater_comm"... Aug 18 08:54:43 volumio-wz volumio[1370]: info: Plugin mpdemulation is not enabled Aug 18 08:54:43 volumio-wz volumio[1370]: info: Loading plugin "rest_api"... Aug 18 08:54:43 volumio-wz volumio[1370]: info: Loading plugin "websocket"... Aug 18 08:54:43 volumio-wz volumio[1370]: info: Starting Socket.io Server version 2.3.0 Aug 18 08:54:43 volumio-wz volumio[1370]: info: Loading plugin "80s80s"... Aug 18 08:54:43 volumio-wz volumio[1370]: Forking 3 albumart workers Aug 18 08:54:44 volumio-wz volumio[1370]: info: Applying required configuration parameters for plugin 80s80s Aug 18 08:54:44 volumio-wz volumio[1370]: info: [1723971284014] [80s80s] API delay: 30 Aug 18 08:54:44 volumio-wz volumio[1370]: info: Loading plugin "lastfm"... Aug 18 08:54:44 volumio-wz kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Aug 18 08:54:45 volumio-wz volumio[1370]: info: Loading i18n strings for locale de Aug 18 08:54:45 volumio-wz volumio[1370]: Updating browse sources language Aug 18 08:54:45 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:45 volumio-wz ifplugd(eth0)[733]: Link beat detected. Aug 18 08:54:45 volumio-wz ifplugd(eth0)[733]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 18 08:54:45 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::initPlayerControls Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: Express server listening on port 3000 Aug 18 08:54:46 volumio-wz volumio[1370]: [Metrics] WebUI: 12s 984.84ms Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: command failed: No such device (-19) Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreStateMachine::resetVolumioState Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreStateMachine::getcurrentVolume Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:54:46 volumio-wz systemd[1]: nmbd.service: Start operation timed out. Terminating. Aug 18 08:54:46 volumio-wz systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Aug 18 08:54:46 volumio-wz systemd[1]: nmbd.service: Failed with result 'timeout'. Aug 18 08:54:46 volumio-wz systemd[1]: Failed to start Samba NMB Daemon. Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: DUID 00:01:00:01:29:81:34:71:b8:27:eb:48:46:85 Aug 18 08:54:46 volumio-wz dhcpcd[1467]: DUID 00:01:00:01:29:81:34:71:b8:27:eb:48:46:85 Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: eth0: IAID eb:48:46:85 Aug 18 08:54:46 volumio-wz dhcpcd[1467]: eth0: IAID eb:48:46:85 Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: eth0: adding address fe80::ba27:ebff:fe48:4685 Aug 18 08:54:46 volumio-wz dhcpcd[1467]: eth0: adding address fe80::ba27:ebff:fe48:4685 Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: ipv6_addaddr1: Permission denied Aug 18 08:54:46 volumio-wz dhcpcd[1467]: ipv6_addaddr1: Permission denied Aug 18 08:54:46 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:46 volumio-wz volumio[1370]: Starting albumart workers Aug 18 08:54:46 volumio-wz volumio[1370]: Starting albumart workers Aug 18 08:54:46 volumio-wz systemd[1]: Starting Samba Winbind Daemon... Aug 18 08:54:46 volumio-wz volumio[1370]: info: Cannot mount NAS pandora at system boot, trial number 1 ,retrying in 5 seconds Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: eth0: rebinding lease of 192.168.10.39 Aug 18 08:54:46 volumio-wz dhcpcd[1467]: eth0: rebinding lease of 192.168.10.39 Aug 18 08:54:46 volumio-wz volumio[1370]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:54:46 volumio-wz ifplugd(eth0)[733]: client: eth0: probing address 192.168.10.39/24 Aug 18 08:54:46 volumio-wz dhcpcd[1467]: eth0: probing address 192.168.10.39/24 Aug 18 08:54:46 volumio-wz volumio[1370]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 18 08:54:47 volumio-wz winbindd[1471]: [2024/08/18 08:54:47.010966, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 18 08:54:47 volumio-wz winbindd[1471]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 18 08:54:47 volumio-wz winbindd[1471]: [2024/08/18 08:54:47.064820, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 18 08:54:47 volumio-wz winbindd[1471]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 18 08:54:47 volumio-wz systemd[1]: Started Samba Winbind Daemon. Aug 18 08:54:47 volumio-wz volumio[1370]: info: Reloading queue from file Aug 18 08:54:47 volumio-wz systemd[1]: Starting Samba SMB Daemon... Aug 18 08:54:47 volumio-wz ifplugd(eth0)[733]: client: eth0: soliciting an IPv6 router Aug 18 08:54:47 volumio-wz dhcpcd[1467]: eth0: soliciting an IPv6 router Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreStateMachine::setRepeat null single undefined Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreStateMachine::pushState Aug 18 08:54:47 volumio-wz volumio[1370]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreStateMachine::setRandom null Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreStateMachine::pushState Aug 18 08:54:47 volumio-wz volumio[1370]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:47 volumio-wz volumio[1370]: Starting albumart workers Aug 18 08:54:47 volumio-wz volumio[1370]: info: Setting Device type: Raspberry PI Aug 18 08:54:47 volumio-wz volumio[1370]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreStateMachine::pushState Aug 18 08:54:47 volumio-wz volumio[1370]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreStateMachine::updateTrackBlock Aug 18 08:54:47 volumio-wz volumio[1370]: info: CorePlayQueue::getTrackBlock Aug 18 08:54:47 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 08:54:47 volumio-wz volumio[1370]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 18 08:54:47 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:47] [connect] Successful connection Aug 18 08:54:48 volumio-wz volumio[1370]: info: Completed loading Core Plugins Aug 18 08:54:48 volumio-wz volumio[1370]: info: Preparing to generate the ALSA configuration file Aug 18 08:54:48 volumio-wz smbd[1480]: [2024/08/18 08:54:48.281239, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 18 08:54:48 volumio-wz systemd[1]: Started Samba SMB Daemon. Aug 18 08:54:48 volumio-wz smbd[1480]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 18 08:54:48 volumio-wz systemd[1]: Reached target Multi-User System. Aug 18 08:54:48 volumio-wz systemd[1]: Reached target Graphical Interface. Aug 18 08:54:48 volumio-wz volumio-remote-updater[466]: [2024-08-18 08:54:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723971287 101 Aug 18 08:54:48 volumio-wz systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 18 08:54:48 volumio-wz volumio[1370]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Aug 18 08:54:48 volumio-wz systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 18 08:54:48 volumio-wz systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 18 08:54:48 volumio-wz systemd[1]: Startup finished in 18.106s (kernel) + 1min 45.816s (userspace) = 2min 3.922s. Aug 18 08:54:48 volumio-wz volumio[1370]: info: VolumeController:: Volume=undefined Mute =false Aug 18 08:54:48 volumio-wz volumio[1370]: info: CoreStateMachine::pushState Aug 18 08:54:48 volumio-wz volumio[1370]: info: CorePlayQueue::getTrack 0 Aug 18 08:54:48 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioPushState Aug 18 08:54:48 volumio-wz sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 08:54:48 volumio-wz sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:48 volumio-wz sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 08:54:48 volumio-wz sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:48 volumio-wz volumio[1370]: info: Asound.conf file unchanged, so no further update is needed Aug 18 08:54:48 volumio-wz sudo[1496]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:48 volumio-wz volumio[1370]: info: Output device has changed, restarting MPD Aug 18 08:54:48 volumio-wz sudo[1502]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:48 volumio-wz volumio[1370]: info: Output device has changed, restarting Shairport Sync Aug 18 08:54:48 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:48 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:48 volumio-wz sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:54:48 volumio-wz sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:54:48 volumio-wz sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:48 volumio-wz sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:48 volumio-wz sudo[1517]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:48 volumio-wz systemd[1]: Stopping Music Player Daemon... Aug 18 08:54:48 volumio-wz volumio[1370]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:48 volumio-wz volumio[1370]: info: ___________ START PLUGINS ___________ Aug 18 08:54:48 volumio-wz volumio[1370]: info: ControllerMpd::onStart: Initializing MPD Aug 18 08:54:48 volumio-wz volumio[1370]: info: Creating MPD Configuration file Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:49 volumio-wz volumio[1370]: info: [1723971289069] CoreMusicLibrary::Adding element Medienserver Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:49 volumio-wz sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 08:54:49 volumio-wz sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:49 volumio-wz systemd[1]: mpd.service: Succeeded. Aug 18 08:54:49 volumio-wz systemd[1]: Stopped Music Player Daemon. Aug 18 08:54:49 volumio-wz sudo[1525]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:49 volumio-wz sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 08:54:49 volumio-wz sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:49 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:49 volumio-wz systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 18 08:54:49 volumio-wz systemd[1]: mpd.service: Succeeded. Aug 18 08:54:49 volumio-wz systemd[1]: Stopped Music Player Daemon. Aug 18 08:54:49 volumio-wz systemd[1]: Starting Music Player Daemon... Aug 18 08:54:49 volumio-wz volumio[1370]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:49 volumio-wz volumio[1370]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:49 volumio-wz volumio[1370]: info: [1723971289410] CoreMusicLibrary::Adding element Last_100 Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:49 volumio-wz volumio[1370]: info: [1723971289420] CoreMusicLibrary::Adding element Webradio Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:49 volumio-wz volumio[1370]: info: Initializing BBC Radios Aug 18 08:54:49 volumio-wz sudo[1534]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 08:54:49 volumio-wz sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:49 volumio-wz sudo[1534]: pam_unix(sudo:session): session closed for user root Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:49 volumio-wz volumio[1370]: info: [1723971289650] CoreMusicLibrary::Adding element 80s80s Radio Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:49 volumio-wz volumio[1370]: Cannot find translation for source 80s80s Radio Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 08:54:49 volumio-wz volumio[1370]: info: [1723971289663] CoreMusicLibrary::Adding element LastFM Aug 18 08:54:49 volumio-wz volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 08:54:49 volumio-wz volumio[1370]: Cannot find translation for source 80s80s Radio Aug 18 08:54:49 volumio-wz volumio[1370]: Cannot find translation for source LastFM Aug 18 08:54:49 volumio-wz volumio[1370]: info: [LastFM] scrobbler initiated! Aug 18 08:54:49 volumio-wz volumio[1370]: info: [LastFM] extended logging: false Aug 18 08:54:49 volumio-wz volumio[1370]: info: [LastFM] try scrobble stream/radio plays: true Aug 18 08:54:49 volumio-wz volumio[1370]: info: [LastFM] Left init routine Aug 18 08:54:49 volumio-wz volumio[1370]: info: [LastFM] Socket already connected: true Aug 18 08:54:49 volumio-wz volumio[1370]: info: Volumio Calling Home Aug 18 08:54:50 volumio-wz volumio[1370]: Unhandled rejection Error: No sockets available, cannot start. Aug 18 08:54:50 volumio-wz volumio[1370]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Aug 18 08:54:50 volumio-wz volumio[1370]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Aug 18 08:54:50 volumio-wz volumio[1370]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Aug 18 08:54:50 volumio-wz volumio[1370]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Aug 18 08:54:50 volumio-wz volumio[1370]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Aug 18 08:54:50 volumio-wz volumio[1370]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Aug 18 08:54:50 volumio-wz volumio[1370]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Aug 18 08:54:50 volumio-wz volumio[1370]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Aug 18 08:54:50 volumio-wz volumio[1370]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Aug 18 08:54:50 volumio-wz volumio[1370]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Aug 18 08:54:50 volumio-wz volumio[1370]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Aug 18 08:54:50 volumio-wz volumio[1370]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 18 08:54:50 volumio-wz volumio[1370]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 18 08:54:50 volumio-wz volumio[1370]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Aug 18 08:54:50 volumio-wz volumio[1370]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Aug 18 08:54:50 volumio-wz volumio[1370]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 18 08:54:50 volumio-wz volumio[1370]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:54:50 volumio-wz volumio[1370]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Aug 18 08:54:50 volumio-wz volumio[1370]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Aug 18 08:54:50 volumio-wz volumio[1370]: errno: -16, Aug 18 08:54:50 volumio-wz volumio[1370]: code: 'EBUSY', Aug 18 08:54:50 volumio-wz volumio[1370]: syscall: 'getaddrinfo', Aug 18 08:54:50 volumio-wz volumio[1370]: hostname: 'ws.audioscrobbler.com' Aug 18 08:54:50 volumio-wz volumio[1370]: } Aug 18 08:54:50 volumio-wz volumio[1370]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 08:54:51 volumio-wz sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 08:53 Aug 18 08:54:51 volumio-wz sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 08:54:51 volumio-wz ifplugd(eth0)[733]: client: eth0: leased 192.168.10.39 for 7200 seconds Aug 18 08:54:51 volumio-wz dhcpcd[1467]: eth0: leased 192.168.10.39 for 7200 seconds Aug 18 08:54:51 volumio-wz avahi-daemon[471]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.39. Aug 18 08:54:51 volumio-wz ifplugd(eth0)[733]: client: eth0: adding route to 192.168.10.0/24 Aug 18 08:54:51 volumio-wz avahi-daemon[471]: New relevant interface eth0.IPv4 for mDNS. Aug 18 08:54:51 volumio-wz dhcpcd[1467]: eth0: adding route to 192.168.10.0/24 Aug 18 08:54:51 volumio-wz avahi-daemon[471]: Registering new address record for 192.168.10.39 on eth0.IPv4. Aug 18 08:54:51 volumio-wz ifplugd(eth0)[733]: client: eth0: adding default route via 192.168.10.253 Aug 18 08:54:51 volumio-wz dhcpcd[1467]: eth0: adding default route via 192.168.10.253 Aug 18 08:54:51 volumio-wz ntpd[715]: ntpd exiting on signal 15 (Terminated) Aug 18 08:54:51 volumio-wz systemd[1]: Stopping Network Time Service... Aug 18 08:54:51 volumio-wz systemd[1]: ntp.service: Succeeded. Aug 18 08:54:51 volumio-wz systemd[1]: Stopped Network Time Service. Aug 18 08:54:51 volumio-wz systemd[1]: Starting Network Time Service... PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"