-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Fri 2022-06-24 03:02:48 UTC. -- Jun 24 03:01:30 blueplayer kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jun 24 03:01:30 blueplayer kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jun 24 03:01:30 blueplayer kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jun 24 03:01:30 blueplayer kernel: usb 1-1: Product: USB2.0 Hub Jun 24 03:01:30 blueplayer kernel: hub 1-1:1.0: USB hub found Jun 24 03:01:30 blueplayer kernel: hub 1-1:1.0: 4 ports detected Jun 24 03:01:30 blueplayer initramfs: /dev/mmcblk0p2: Jun 24 03:01:30 blueplayer kernel: EXT4-fs (mmcblk0p2): recovery complete Jun 24 03:01:30 blueplayer kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jun 24 03:01:30 blueplayer initramfs: Doing a 5 second delay here to give kernel load a headstart Jun 24 03:01:30 blueplayer kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Jun 24 03:01:30 blueplayer kernel: usb 1-1.2: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Jun 24 03:01:30 blueplayer kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 24 03:01:30 blueplayer kernel: usb 1-1.2: Product: Combo384 Amanero Jun 24 03:01:30 blueplayer kernel: usb 1-1.2: Manufacturer: Amanero Technologies Jun 24 03:01:30 blueplayer kernel: usb 1-1.2: SerialNumber: 413-001 Jun 24 03:01:30 blueplayer initramfs: Checking for USB updates Jun 24 03:01:30 blueplayer initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jun 24 03:01:30 blueplayer kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jun 24 03:01:30 blueplayer kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jun 24 03:01:30 blueplayer kernel: EXT4-fs (mmcblk0p3): 2 orphan inodes deleted Jun 24 03:01:30 blueplayer kernel: EXT4-fs (mmcblk0p3): recovery complete Jun 24 03:01:30 blueplayer kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jun 24 03:01:30 blueplayer initramfs: With Option: Jun 24 03:01:30 blueplayer initramfs: VOLUMIO_VERSION="3.301" Jun 24 03:01:30 blueplayer initramfs: Finish initramfs, continue booting Volumio Jun 24 03:01:30 blueplayer systemd[1]: System time before build time, advancing clock. Jun 24 03:01:30 blueplayer kernel: NET: Registered protocol family 10 Jun 24 03:01:30 blueplayer kernel: Segment Routing with IPv6 Jun 24 03:01:30 blueplayer 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) Jun 24 03:01:30 blueplayer systemd[1]: Detected architecture arm. Jun 24 03:01:30 blueplayer systemd[1]: Set hostname to . Jun 24 03:01:30 blueplayer kernel: uart-pl011 fe201000.serial: no DMA platform data Jun 24 03:01:30 blueplayer 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. Jun 24 03:01:30 blueplayer systemd[1]: Configuration file /lib/systemd/system/voltc.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 24 03:01:30 blueplayer systemd[1]: Configuration file /lib/systemd/system/soundcheck.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 24 03:01:30 blueplayer systemd[1]: Configuration file /lib/systemd/system/soundcheck.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jun 24 03:01:30 blueplayer kernel: random: systemd: uninitialized urandom read (16 bytes read) Jun 24 03:01:30 blueplayer kernel: random: systemd: uninitialized urandom read (16 bytes read) Jun 24 03:01:30 blueplayer kernel: random: systemd: uninitialized urandom read (16 bytes read) Jun 24 03:01:30 blueplayer systemd[1]: Listening on Journal Socket (/dev/log). Jun 24 03:01:30 blueplayer systemd[1]: Listening on udev Control Socket. Jun 24 03:01:30 blueplayer systemd[1]: Listening on fsck to fsckd communication Socket. Jun 24 03:01:30 blueplayer kernel: i2c /dev entries driver Jun 24 03:01:30 blueplayer systemd-journald[200]: Journal started Jun 24 03:01:30 blueplayer systemd-journald[200]: Runtime journal (/run/log/journal/ea88bf60193e624c52413a0161ae0e6e) is 7.5M, max 30.0M, 22.5M free. Jun 24 03:01:30 blueplayer systemd-modules-load[191]: Inserted module 'i2c_dev' Jun 24 03:01:30 blueplayer fake-hwclock[198]: Fri Jun 24 03:01:30 UTC 2022 Jun 24 03:01:30 blueplayer systemd[1]: Started Load/Save Random Seed. Jun 24 03:01:30 blueplayer systemd[1]: dynamicswap.service: Succeeded. Jun 24 03:01:30 blueplayer systemd[1]: Started Create System Users. Jun 24 03:01:30 blueplayer systemd[1]: Starting Create Static Device Nodes in /dev... Jun 24 03:01:30 blueplayer systemd[1]: Started udev Coldplug all Devices. Jun 24 03:01:30 blueplayer systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 24 03:01:30 blueplayer systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 24 03:01:30 blueplayer systemd[1]: Started Create Static Device Nodes in /dev. Jun 24 03:01:30 blueplayer systemd[1]: Reached target Local File Systems (Pre). Jun 24 03:01:30 blueplayer systemd[1]: Mounting /var/spool/cups... Jun 24 03:01:30 blueplayer systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 24 03:01:30 blueplayer systemd[1]: Mounting /tmp... Jun 24 03:01:30 blueplayer systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 24 03:01:30 blueplayer systemd[1]: Mounting /var/log... Jun 24 03:01:30 blueplayer systemd[1]: Starting udev Kernel Device Manager... Jun 24 03:01:30 blueplayer systemd[1]: Mounted /var/spool/cups. Jun 24 03:01:30 blueplayer systemd[1]: Mounted /tmp. Jun 24 03:01:30 blueplayer systemd[1]: Mounted /var/log. Jun 24 03:01:30 blueplayer systemd[1]: Starting Flush Journal to Persistent Storage... Jun 24 03:01:30 blueplayer systemd[1]: Mounting /var/spool/cups/tmp... Jun 24 03:01:30 blueplayer systemd-udevd[224]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 24 03:01:30 blueplayer systemd[1]: Mounted /var/spool/cups/tmp. Jun 24 03:01:30 blueplayer systemd-journald[200]: Runtime journal (/run/log/journal/ea88bf60193e624c52413a0161ae0e6e) is 7.5M, max 30.0M, 22.5M free. Jun 24 03:01:30 blueplayer systemd[1]: Started udev Kernel Device Manager. Jun 24 03:01:30 blueplayer systemd[1]: Starting Show Plymouth Boot Screen... Jun 24 03:01:30 blueplayer systemd[1]: Started Flush Journal to Persistent Storage. Jun 24 03:01:30 blueplayer systemd[1]: Received SIGRTMIN+20 from PID 248 (plymouthd). Jun 24 03:01:30 blueplayer systemd[1]: Started Show Plymouth Boot Screen. Jun 24 03:01:30 blueplayer systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 24 03:01:30 blueplayer systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 24 03:01:30 blueplayer systemd[1]: Reached target Paths. Jun 24 03:01:30 blueplayer systemd[1]: Reached target Local Encrypted Volumes. Jun 24 03:01:30 blueplayer systemd-udevd[252]: Using default interface naming scheme 'v240'. Jun 24 03:01:30 blueplayer kernel: mc: Linux media interface: v0.10 Jun 24 03:01:30 blueplayer kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jun 24 03:01:30 blueplayer kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jun 24 03:01:30 blueplayer kernel: [vc_sm_connected_init]: start Jun 24 03:01:30 blueplayer kernel: [vc_sm_connected_init]: installed successfully Jun 24 03:01:30 blueplayer kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Jun 24 03:01:30 blueplayer kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Jun 24 03:01:30 blueplayer kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Jun 24 03:01:30 blueplayer kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Jun 24 03:01:30 blueplayer systemd-udevd[234]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 24 03:01:30 blueplayer systemd-udevd[252]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 24 03:01:30 blueplayer kernel: videodev: Linux video capture interface: v2.00 Jun 24 03:01:30 blueplayer kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 24 03:01:30 blueplayer kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jun 24 03:01:30 blueplayer kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jun 24 03:01:30 blueplayer kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 24 03:01:30 blueplayer kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jun 24 03:01:30 blueplayer kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jun 24 03:01:30 blueplayer kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jun 24 03:01:30 blueplayer kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 24 03:01:30 blueplayer kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 24 03:01:30 blueplayer kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 24 03:01:31 blueplayer kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 24 03:01:31 blueplayer kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jun 24 03:01:31 blueplayer kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 24 03:01:31 blueplayer kernel: usbcore: registered new interface driver brcmfmac Jun 24 03:01:31 blueplayer systemd[1]: Found device /dev/ttyAMA0. Jun 24 03:01:31 blueplayer kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Jun 24 03:01:31 blueplayer kernel: usb 1-1.2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Jun 24 03:01:31 blueplayer kernel: usb 1-1.2: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Jun 24 03:01:31 blueplayer kernel: usb 1-1.2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Jun 24 03:01:31 blueplayer kernel: usb 1-1.2: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Jun 24 03:01:31 blueplayer kernel: usbcore: registered new interface driver snd-usb-audio Jun 24 03:01:31 blueplayer kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 24 03:01:31 blueplayer kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 24 03:01:31 blueplayer systemd-udevd[229]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jun 24 03:01:31 blueplayer kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e Jun 24 03:01:31 blueplayer systemd-udevd[229]: Using default interface naming scheme 'v240'. Jun 24 03:01:31 blueplayer systemd-udevd[229]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 24 03:01:31 blueplayer systemd[1]: Found device /dev/mmcblk0p1. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jun 24 03:01:31 blueplayer systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 24 03:01:31 blueplayer systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jun 24 03:01:31 blueplayer systemd[1]: Started File System Check Daemon to report status. Jun 24 03:01:31 blueplayer systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 24 03:01:31 blueplayer systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jun 24 03:01:31 blueplayer systemd[1]: Started Load/Save RF Kill Switch Status. Jun 24 03:01:31 blueplayer systemd-fsck[376]: fsck.fat 4.1 (2017-01-24) Jun 24 03:01:31 blueplayer systemd-fsck[376]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jun 24 03:01:31 blueplayer systemd-fsck[376]: Automatically removing dirty bit. Jun 24 03:01:31 blueplayer systemd-fsck[376]: Performing changes. Jun 24 03:01:31 blueplayer systemd-fsck[376]: /dev/mmcblk0p1: 303 files, 27492/46774 clusters Jun 24 03:01:31 blueplayer systemd[1]: Started File System Check on /dev/mmcblk0p1. Jun 24 03:01:31 blueplayer systemd[1]: Mounting /boot... Jun 24 03:01:31 blueplayer systemd[1]: Mounted /boot. Jun 24 03:01:31 blueplayer systemd[1]: Reached target Local File Systems. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Started ifup for eth0. Jun 24 03:01:31 blueplayer systemd[1]: Starting Preprocess NFS configuration... Jun 24 03:01:31 blueplayer systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 24 03:01:31 blueplayer systemd[1]: Starting Create Volatile Files and Directories... Jun 24 03:01:31 blueplayer systemd[1]: Started ifup for wlan0. Jun 24 03:01:31 blueplayer systemd[1]: Starting Raise network interfaces... Jun 24 03:01:31 blueplayer systemd[1]: plymouth-read-write.service: Succeeded. Jun 24 03:01:31 blueplayer systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 24 03:01:31 blueplayer systemd[1]: nfs-config.service: Succeeded. Jun 24 03:01:31 blueplayer systemd[1]: Started Preprocess NFS configuration. Jun 24 03:01:31 blueplayer systemd[1]: Received SIGRTMIN+20 from PID 248 (plymouthd). Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 24 03:01:31 blueplayer systemd[1]: Reached target NFS client services. Jun 24 03:01:32 blueplayer systemd[1]: Started Create Volatile Files and Directories. Jun 24 03:01:32 blueplayer systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 24 03:01:32 blueplayer systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 24 03:01:32 blueplayer systemd[1]: Starting RPC bind portmap service... Jun 24 03:01:32 blueplayer systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 24 03:01:32 blueplayer systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 24 03:01:32 blueplayer systemd[1]: Reached target System Initialization. Jun 24 03:01:32 blueplayer systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 24 03:01:32 blueplayer systemd[1]: apt-daily.timer: Not using persistent file timestamp Mon 2024-07-08 06:04:04 UTC as it is in the future. Jun 24 03:01:32 blueplayer systemd[1]: Started Daily apt download activities. Jun 24 03:01:32 blueplayer systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Mon 2024-07-08 06:04:04 UTC as it is in the future. Jun 24 03:01:32 blueplayer systemd[1]: Started Daily apt upgrade and clean activities. Jun 24 03:01:32 blueplayer systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 24 03:01:32 blueplayer systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 24 03:01:32 blueplayer systemd[1]: Listening on mpd.socket. Jun 24 03:01:32 blueplayer systemd[1]: Listening on triggerhappy.socket. Jun 24 03:01:32 blueplayer systemd[1]: Reached target Sockets. Jun 24 03:01:32 blueplayer systemd[1]: Reached target Basic System. Jun 24 03:01:32 blueplayer systemd[1]: Started Manage Sound Card State (restore and store). Jun 24 03:01:32 blueplayer systemd[1]: Started D-Bus System Message Bus. Jun 24 03:01:32 blueplayer alsactl[456]: alsactl 1.1.8 daemon started Jun 24 03:01:32 blueplayer systemd[1]: Started Sound check for Addon. Jun 24 03:01:32 blueplayer systemd[1]: Starting Wireless Services... Jun 24 03:01:32 blueplayer systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Jun 24 03:01:32 blueplayer systemd[1]: Started UPnP Renderer front-end to MPD. Jun 24 03:01:32 blueplayer systemd[1]: Started SysPi. Jun 24 03:01:32 blueplayer systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 24 03:01:32 blueplayer kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 24 03:01:32 blueplayer systemd[1]: Starting Login Service... Jun 24 03:01:32 blueplayer kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 24 03:01:32 blueplayer systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 24 03:01:32 blueplayer systemd[1]: Starting WPA supplicant... Jun 24 03:01:32 blueplayer systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 24 03:01:32 blueplayer systemd[1]: Started volumio-remote-updater.service. Jun 24 03:01:32 blueplayer systemd[1]: Started RoonBridge. Jun 24 03:01:32 blueplayer systemd[1]: Starting Save/Restore Sound Card State... Jun 24 03:01:32 blueplayer systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 24 03:01:32 blueplayer systemd[1]: Starting dhcpcd on all interfaces... Jun 24 03:01:32 blueplayer systemd[1]: man-db.timer: Not using persistent file timestamp Mon 2024-07-08 06:04:04 UTC as it is in the future. Jun 24 03:01:32 blueplayer systemd[1]: Started Daily man-db regeneration. Jun 24 03:01:32 blueplayer systemd[1]: Reached target Timers. Jun 24 03:01:32 blueplayer systemd[1]: Started Volumio Iptables Module. Jun 24 03:01:32 blueplayer systemd[1]: Starting triggerhappy global hotkey daemon... Jun 24 03:01:32 blueplayer systemd[1]: Started RPC bind portmap service. Jun 24 03:01:32 blueplayer systemd[1]: sys.service: Succeeded. Jun 24 03:01:32 blueplayer systemd[1]: Reached target Remote File Systems (Pre). Jun 24 03:01:32 blueplayer systemd[1]: Reached target Remote File Systems. Jun 24 03:01:32 blueplayer systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 24 03:01:32 blueplayer systemd[1]: Reached target RPC Port Mapper. Jun 24 03:01:32 blueplayer dhcpcd[474]: Not running dhcpcd because /etc/network/interfaces Jun 24 03:01:32 blueplayer dhcpcd[474]: defines some interfaces that will use a Jun 24 03:01:32 blueplayer dhcpcd[474]: DHCP client or static address Jun 24 03:01:32 blueplayer thd[476]: Unable to parse trigger line: Jun 24 03:01:32 blueplayer thd[476]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 24 03:01:32 blueplayer thd[476]: Unable to parse trigger line: Jun 24 03:01:32 blueplayer thd[476]: Found socket passed from systemd Jun 24 03:01:32 blueplayer bash[458]: end Jun 24 03:01:32 blueplayer bash[458]: endplay Jun 24 03:01:32 blueplayer systemd-logind[462]: New seat seat0. Jun 24 03:01:32 blueplayer avahi-daemon[469]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 24 03:01:32 blueplayer avahi-daemon[469]: Successfully dropped root privileges. Jun 24 03:01:32 blueplayer avahi-daemon[469]: avahi-daemon 0.7 starting up. Jun 24 03:01:32 blueplayer sh[383]: eth0: waiting for carrier Jun 24 03:01:32 blueplayer dhcpcd[440]: eth0: waiting for carrier Jun 24 03:01:32 blueplayer kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jun 24 03:01:32 blueplayer kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jun 24 03:01:32 blueplayer systemd[1]: Started triggerhappy global hotkey daemon. Jun 24 03:01:32 blueplayer systemd[1]: soundcheck.service: Succeeded. Jun 24 03:01:32 blueplayer systemd[1]: Started Save/Restore Sound Card State. Jun 24 03:01:32 blueplayer systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 24 03:01:32 blueplayer systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 24 03:01:32 blueplayer systemd[1]: Failed to start dhcpcd on all interfaces. Jun 24 03:01:32 blueplayer avahi-daemon[469]: Successfully called chroot(). Jun 24 03:01:32 blueplayer avahi-daemon[469]: Successfully dropped remaining capabilities. Jun 24 03:01:32 blueplayer avahi-daemon[469]: Loading service file /services/volumio.service. Jun 24 03:01:32 blueplayer avahi-daemon[469]: Network interface enumeration completed. Jun 24 03:01:32 blueplayer avahi-daemon[469]: Server startup complete. Host name is blueplayer.local. Local service cookie is 414140729. Jun 24 03:01:32 blueplayer avahi-daemon[469]: Service "Blueplayer" (/services/volumio.service) successfully established. Jun 24 03:01:32 blueplayer systemd[1]: Started Login Service. Jun 24 03:01:32 blueplayer systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 24 03:01:32 blueplayer systemd[1]: Reached target Sound Card. Jun 24 03:01:32 blueplayer volumio-remote-updater[471]: /usr/local/bin/volumio-remote-updater: /usr/lib/arm-linux-gnueabihf/libcurl.so.4: no version information available (required by /usr/local/bin/volumio-remote-updater) Jun 24 03:01:33 blueplayer loadcpufreq[478]: Loading cpufreq kernel modules...done (none). Jun 24 03:01:33 blueplayer systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 24 03:01:33 blueplayer systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 24 03:01:33 blueplayer cpufrequtils[557]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 24 03:01:33 blueplayer volumio-remote-updater[471]: Error: No active session Jun 24 03:01:33 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:33] [info] asio async_connect error: system:111 (Connection refused) Jun 24 03:01:33 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 24 03:01:33 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:33] [error] handle_connect error: Underlying Transport Error Jun 24 03:01:33 blueplayer systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 24 03:01:33 blueplayer sudo[530]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:33 blueplayer sudo[530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:33 blueplayer sudo[526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[526]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[530]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[579]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:33 blueplayer sudo[579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[580]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:33 blueplayer sudo[580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[580]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[579]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer ifup[391]: dc:a6:32:6d:07:2f10000000b57d5c3a Jun 24 03:01:33 blueplayer ifup[391]: OK Jun 24 03:01:33 blueplayer sh[389]: dc:a6:32:6d:07:2f10000000b57d5c3a Jun 24 03:01:33 blueplayer sh[389]: OK Jun 24 03:01:33 blueplayer systemd[1]: Started WPA supplicant. Jun 24 03:01:33 blueplayer wpa_supplicant[470]: Successfully initialized wpa_supplicant Jun 24 03:01:33 blueplayer sudo[605]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/V3.done Jun 24 03:01:33 blueplayer sudo[605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/V3.done Jun 24 03:01:33 blueplayer sudo[604]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer systemd[1]: iptables.service: Succeeded. Jun 24 03:01:33 blueplayer sudo[604]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[605]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/AK4497 Jun 24 03:01:33 blueplayer sudo[610]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/AK4497 Jun 24 03:01:33 blueplayer sudo[610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[610]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer ifup[391]: run-parts: /etc/network/if-up.d/net exited with return code 1 Jun 24 03:01:33 blueplayer ifup[391]: ifup: failed to bring up lo Jun 24 03:01:33 blueplayer sudo[609]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sh[389]: run-parts: /etc/network/if-up.d/net exited with return code 1 Jun 24 03:01:33 blueplayer sh[389]: ifup: failed to bring up wlan0 Jun 24 03:01:33 blueplayer systemd[1]: ifup@wlan0.service: Main process exited, code=exited, status=1/FAILURE Jun 24 03:01:33 blueplayer systemd[1]: ifup@wlan0.service: Failed with result 'exit-code'. Jun 24 03:01:33 blueplayer haveged[434]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 24 03:01:33 blueplayer haveged[434]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 24 03:01:33 blueplayer haveged[434]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00024 Jun 24 03:01:33 blueplayer haveged[434]: haveged: fills: 0, generated: 0 Jun 24 03:01:33 blueplayer sudo[632]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:33 blueplayer kernel: random: crng init done Jun 24 03:01:33 blueplayer kernel: random: 7 urandom warning(s) missed due to ratelimiting Jun 24 03:01:33 blueplayer sudo[632]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[632]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[634]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:33 blueplayer sudo[634]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[634]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer ifup[391]: dc:a6:32:6d:07:2f10000000b57d5c3a Jun 24 03:01:33 blueplayer ifup[391]: OK Jun 24 03:01:33 blueplayer sudo[645]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/V3.done Jun 24 03:01:33 blueplayer sudo[645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[645]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer sudo[647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/AK4497 Jun 24 03:01:33 blueplayer sudo[647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:33 blueplayer sudo[647]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:33 blueplayer ifup[391]: run-parts: /etc/network/if-up.d/net exited with return code 1 Jun 24 03:01:33 blueplayer ifup[391]: ifup: post-up script failed Jun 24 03:01:33 blueplayer systemd[1]: networking.service: Main process exited, code=exited, status=1/FAILURE Jun 24 03:01:33 blueplayer systemd[1]: networking.service: Failed with result 'exit-code'. Jun 24 03:01:33 blueplayer volumio[460]: Could not open config: /tmp/upmpdcli.conf Jun 24 03:01:33 blueplayer systemd[1]: Failed to start Raise network interfaces. Jun 24 03:01:33 blueplayer systemd[1]: Reached target Network. Jun 24 03:01:33 blueplayer systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 24 03:01:33 blueplayer systemd[1]: Starting Network Time Service... Jun 24 03:01:33 blueplayer systemd[1]: Starting Permit User Sessions... Jun 24 03:01:33 blueplayer systemd[1]: Starting Music Player Daemon... Jun 24 03:01:33 blueplayer systemd[1]: Reached target Network is Online. Jun 24 03:01:33 blueplayer systemd[1]: Starting /etc/rc.local Compatibility... Jun 24 03:01:33 blueplayer systemd[1]: Started volumiomq. Jun 24 03:01:33 blueplayer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 24 03:01:33 blueplayer systemd[1]: Started volumiose. Jun 24 03:01:33 blueplayer systemd[1]: Started volumiosmone. Jun 24 03:01:33 blueplayer systemd[1]: Starting Samba NMB Daemon... Jun 24 03:01:33 blueplayer systemd[1]: Started volumiobol. Jun 24 03:01:33 blueplayer systemd[1]: Starting LSB: Brings up/down network automatically... Jun 24 03:01:33 blueplayer systemd[1]: Started volumiohir. Jun 24 03:01:33 blueplayer RoonBridge[472]: 00:00:00.012 Warn: get lock file path: /tmp/.rnbgem0- Jun 24 03:01:33 blueplayer systemd[1]: Started volumiods. Jun 24 03:01:34 blueplayer systemd[1]: Started volumiocdt. Jun 24 03:01:34 blueplayer systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 24 03:01:34 blueplayer systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 24 03:01:34 blueplayer systemd[1]: Started Permit User Sessions. Jun 24 03:01:34 blueplayer systemd[1]: Started /etc/rc.local Compatibility. Jun 24 03:01:34 blueplayer systemd[1]: Starting Hold until boot process finishes up... Jun 24 03:01:34 blueplayer systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 24 03:01:34 blueplayer systemd[1]: Received SIGRTMIN+21 from PID 248 (plymouthd). Jun 24 03:01:34 blueplayer systemd[1]: Received SIGRTMIN+21 from PID 248 (plymouthd). Jun 24 03:01:34 blueplayer systemd[1]: plymouth-quit.service: Succeeded. Jun 24 03:01:34 blueplayer systemd[1]: Started Terminate Plymouth Boot Screen. Jun 24 03:01:34 blueplayer systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 24 03:01:34 blueplayer systemd[1]: Started Hold until boot process finishes up. Jun 24 03:01:34 blueplayer systemd[1]: plymouth-start.service: Succeeded. Jun 24 03:01:34 blueplayer ntpd[649]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 24 03:01:34 blueplayer ntpd[649]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 24 03:01:34 blueplayer systemd[1]: Started Getty on tty1. Jun 24 03:01:34 blueplayer systemd[1]: Started Serial Getty on ttyAMA0. Jun 24 03:01:34 blueplayer systemd[1]: Reached target Login Prompts. Jun 24 03:01:34 blueplayer systemd[1]: Started Network Time Service. Jun 24 03:01:34 blueplayer ntpd[686]: proto: precision = 0.815 usec (-20) Jun 24 03:01:34 blueplayer ifplugd(eth0)[716]: ifplugd 0.28 initializing. Jun 24 03:01:34 blueplayer ifplugd(eth0)[716]: Using interface eth0/DC:A6:32:6D:07:2F with driver (version: 5.10.92-v7l+) Jun 24 03:01:34 blueplayer ifplugd(eth0)[716]: Using detection mode: SIOCETHTOOL Jun 24 03:01:34 blueplayer ifplugd(eth0)[716]: Initialization complete, link beat not detected. Jun 24 03:01:34 blueplayer ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 24 03:01:34 blueplayer ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 24 03:01:34 blueplayer ntpd[686]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): will expire in less than 4 days Jun 24 03:01:34 blueplayer ntpd[686]: Listen and drop on 0 v6wildcard [::]:123 Jun 24 03:01:34 blueplayer ntpd[686]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 24 03:01:34 blueplayer ntpd[686]: Listen normally on 2 lo 127.0.0.1:123 Jun 24 03:01:34 blueplayer ntpd[686]: Listening on routing socket on fd #19 for interface updates Jun 24 03:01:34 blueplayer ntpd[686]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 24 03:01:34 blueplayer ntpd[686]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 24 03:01:34 blueplayer ifplugd[663]: Network Interface Plugging Daemon...start eth0...done. Jun 24 03:01:34 blueplayer systemd[1]: Started LSB: Brings up/down network automatically. Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.637 Trace: [childprocess] using unix child process Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.715 Debug: PathForResource, filename: ../.update Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.858 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.866 Debug: PathForResource, filename: RAATServer Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.866 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.866 Debug: PathForResource, filename: RoonBridgeHelper Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.867 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jun 24 03:01:34 blueplayer RoonBridge[472]: Initializing Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.908 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jun 24 03:01:34 blueplayer RoonBridge[472]: 00:00:00.956 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jun 24 03:01:34 blueplayer RoonBridge[472]: Not Running (.o) Jun 24 03:01:35 blueplayer ntpd[686]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 24 03:01:35 blueplayer RoonBridge[472]: Running Jun 24 03:01:35 blueplayer nmbd[671]: [2022/06/24 03:01:35.762984, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 24 03:01:35 blueplayer nmbd[671]: started asyncdns process 768 Jun 24 03:01:35 blueplayer nmbd[671]: [2022/06/24 03:01:35.764964, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 24 03:01:35 blueplayer nmbd[671]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 24 03:01:35 blueplayer nmbd[671]: [2022/06/24 03:01:35.765111, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 24 03:01:35 blueplayer nmbd[671]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 24 03:01:36 blueplayer RoonBridge[472]: 00:00:00.025 Warn: get lock file path: /tmp/.rnbhgem0- Jun 24 03:01:36 blueplayer ntpd[686]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 24 03:01:36 blueplayer mpd[713]: /usr/bin/mpd: /usr/lib/arm-linux-gnueabihf/libcurl.so.4: no version information available (required by /usr/bin/mpd) Jun 24 03:01:36 blueplayer wireless.js[459]: Cleaning previous... Jun 24 03:01:36 blueplayer sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 24 03:01:36 blueplayer sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:36 blueplayer sudo[779]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:36 blueplayer sudo[781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 24 03:01:36 blueplayer sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:36 blueplayer kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jun 24 03:01:36 blueplayer systemd[1]: systemd-rfkill.service: Succeeded. Jun 24 03:01:37 blueplayer sh[383]: eth0: carrier acquired Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: carrier acquired Jun 24 03:01:37 blueplayer sudo[781]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:37 blueplayer sh[383]: DUID 00:01:00:01:29:40:cc:9c:dc:a6:32:18:67:22 Jun 24 03:01:37 blueplayer sh[383]: eth0: IAID 32:6d:07:2f Jun 24 03:01:37 blueplayer dhcpcd[440]: DUID 00:01:00:01:29:40:cc:9c:dc:a6:32:18:67:22 Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: IAID 32:6d:07:2f Jun 24 03:01:37 blueplayer sh[383]: eth0: adding address fe80::ef28:cdbd:6d34:fc08 Jun 24 03:01:37 blueplayer sh[383]: ipv6_addaddr1: Permission denied Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: adding address fe80::ef28:cdbd:6d34:fc08 Jun 24 03:01:37 blueplayer dhcpcd[440]: ipv6_addaddr1: Permission denied Jun 24 03:01:37 blueplayer mpd[713]: Jun 24 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 03:01:37 blueplayer ntpd[686]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 24 03:01:37 blueplayer wireless.js[459]: Stopped aP Jun 24 03:01:37 blueplayer sudo[794]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 24 03:01:37 blueplayer sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:37 blueplayer sudo[794]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:37 blueplayer ifplugd(eth0)[716]: Link beat detected. Jun 24 03:01:37 blueplayer sudo[797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 24 03:01:37 blueplayer sudo[797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:37 blueplayer sudo[797]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:37 blueplayer avahi-daemon[469]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 24 03:01:37 blueplayer kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 24 03:01:37 blueplayer avahi-daemon[469]: New relevant interface wlan0.IPv4 for mDNS. Jun 24 03:01:37 blueplayer avahi-daemon[469]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jun 24 03:01:37 blueplayer systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jun 24 03:01:37 blueplayer ifplugd(eth0)[716]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 24 03:01:37 blueplayer systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jun 24 03:01:37 blueplayer dnsmasq[803]: dnsmasq: syntax check OK. Jun 24 03:01:37 blueplayer ifplugd(eth0)[716]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jun 24 03:01:37 blueplayer hostapd[805]: Configuration file: /etc/hostapd/hostapd.conf Jun 24 03:01:37 blueplayer hostapd[805]: wlan0: Could not connect to kernel driver Jun 24 03:01:37 blueplayer hostapd[805]: Using interface wlan0 with hwaddr dc:a6:32:6d:07:31 and ssid "blueplayer" Jun 24 03:01:37 blueplayer sh[383]: eth0: soliciting a DHCP lease Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: soliciting a DHCP lease Jun 24 03:01:37 blueplayer sh[383]: eth0: offered 192.168.1.118 from 192.168.1.1 Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: offered 192.168.1.118 from 192.168.1.1 Jun 24 03:01:37 blueplayer hostapd[805]: wlan0: interface state UNINITIALIZED->ENABLED Jun 24 03:01:37 blueplayer hostapd[805]: wlan0: AP-ENABLED Jun 24 03:01:37 blueplayer systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jun 24 03:01:37 blueplayer dnsmasq[819]: started, version 2.80 cachesize 150 Jun 24 03:01:37 blueplayer dnsmasq[819]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jun 24 03:01:37 blueplayer dnsmasq-dhcp[819]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jun 24 03:01:37 blueplayer dnsmasq[819]: reading /etc/resolv.conf Jun 24 03:01:37 blueplayer dnsmasq[819]: using nameserver 208.67.222.222#53 Jun 24 03:01:37 blueplayer dnsmasq[819]: using nameserver 208.67.220.220#53 Jun 24 03:01:37 blueplayer dnsmasq[819]: read /etc/hosts - 1 addresses Jun 24 03:01:37 blueplayer systemd[1]: wireless.service: Succeeded. Jun 24 03:01:37 blueplayer systemd[1]: Started Wireless Services. Jun 24 03:01:37 blueplayer systemd[1]: Started Volumio Backend Module. Jun 24 03:01:37 blueplayer systemd[1]: Started TC Connect Service. Jun 24 03:01:37 blueplayer systemd[1]: Started Volumio Streaming Daemon. Jun 24 03:01:37 blueplayer systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jun 24 03:01:37 blueplayer systemd[1]: Reached target Host and Network Name Lookups. Jun 24 03:01:37 blueplayer sh[383]: eth0: soliciting an IPv6 router Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: soliciting an IPv6 router Jun 24 03:01:37 blueplayer tidal_connect[827]: /opt/tid/bin/tidal_connect: /usr/lib/arm-linux-gnueabihf/libcurl.so.4: no version information available (required by /opt/tid/bin/tidal_connect) Jun 24 03:01:37 blueplayer sh[383]: eth0: probing address 192.168.1.118/24 Jun 24 03:01:37 blueplayer dhcpcd[440]: eth0: probing address 192.168.1.118/24 Jun 24 03:01:37 blueplayer tidal_connect[827]: STARTING TidalConnect services, version: 1.1.3 Jun 24 03:01:38 blueplayer volumio-streaming-daemon[829]: ############################ Jun 24 03:01:38 blueplayer volumio-streaming-daemon[829]: # Volumio Streaming Daemon # Jun 24 03:01:38 blueplayer volumio-streaming-daemon[829]: # Running on port 7777 # Jun 24 03:01:38 blueplayer volumio-streaming-daemon[829]: ############################ Jun 24 03:01:38 blueplayer volumio-streaming-daemon[829]: Environment: production Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.front.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM front Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.rear Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.center_lfe Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.side Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround21 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround21 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround40.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround40 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround41 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround50 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround51.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround51 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.surround71.0:CARD=0' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM surround71 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM iec958 Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM spdif Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=4,AES1=130,AES2=0,AES3=2' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM spdif Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.modem Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.phoneline Jun 24 03:01:38 blueplayer ntpd[686]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 24 03:01:38 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:38] [info] asio async_connect error: system:111 (Connection refused) Jun 24 03:01:38 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 24 03:01:38 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:38] [error] handle_connect error: Underlying Transport Error Jun 24 03:01:38 blueplayer tidal_connect[827]: Cannot connect to server socket err = No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: Cannot connect to server request channel Jun 24 03:01:38 blueplayer tidal_connect[827]: jack server is not running or cannot be started Jun 24 03:01:38 blueplayer tidal_connect[827]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Jun 24 03:01:38 blueplayer tidal_connect[827]: Cannot connect to server socket err = No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: Cannot connect to server request channel Jun 24 03:01:38 blueplayer tidal_connect[827]: jack server is not running or cannot be started Jun 24 03:01:38 blueplayer tidal_connect[827]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm_oss.c:377:(_snd_pcm_oss_open) Unknown field port Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm_oss.c:377:(_snd_pcm_oss_open) Unknown field port Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pulse.c:243:(pulse_connect) PulseAudio: Unable to connect: Connection refused Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm_a52.c:823:(_snd_pcm_a52_open) a52 is only for playback Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib confmisc.c:1281:(snd_func_refer) Unable to find definition 'cards.bcm2835_hdmi.pcm.iec958.0:CARD=0,AES0=6,AES1=130,AES2=0,AES3=2' Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:4568:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib conf.c:5047:(snd_config_expand) Evaluate error: No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm.c:2565:(snd_pcm_open_noupdate) Unknown PCM iec958:{AES0 0x6 AES1 0x82 AES2 0x0 AES3 0x2 CARD 0} Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm_usb_stream.c:486:(_snd_pcm_usb_stream_open) Invalid type for card Jun 24 03:01:38 blueplayer tidal_connect[827]: ALSA lib pcm_usb_stream.c:486:(_snd_pcm_usb_stream_open) Invalid type for card Jun 24 03:01:38 blueplayer tidal_connect[827]: Cannot connect to server socket err = No such file or directory Jun 24 03:01:38 blueplayer tidal_connect[827]: Cannot connect to server request channel Jun 24 03:01:38 blueplayer tidal_connect[827]: jack server is not running or cannot be started Jun 24 03:01:38 blueplayer tidal_connect[827]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock Jun 24 03:01:38 blueplayer tidal_connect[827]: STARTED TidalConnect services. Jun 24 03:01:39 blueplayer ntpd[686]: Listen normally on 3 wlan0 192.168.211.1:123 Jun 24 03:01:39 blueplayer ntpd[686]: new interface(s) found: waking up resolver Jun 24 03:01:40 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:40 blueplayer volumio[825]: info: ----- Volumio3 ---- Jun 24 03:01:40 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:40 blueplayer volumio[825]: info: ----- System startup ---- Jun 24 03:01:40 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:40 blueplayer mpd[713]: Jun 24 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 24 03:01:40 blueplayer mpd[713]: Jun 24 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 24 03:01:40 blueplayer systemd[1]: Started Music Player Daemon. Jun 24 03:01:40 blueplayer systemd[1]: Started Samba NMB Daemon. Jun 24 03:01:40 blueplayer nmbd[671]: [2022/06/24 03:01:40.778626, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 24 03:01:40 blueplayer nmbd[671]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 24 03:01:40 blueplayer systemd[1]: Starting Samba Winbind Daemon... Jun 24 03:01:40 blueplayer volumio[825]: info: MYVOLUMIO Environment detected Jun 24 03:01:40 blueplayer winbindd[887]: [2022/06/24 03:01:40.998936, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 24 03:01:40 blueplayer winbindd[887]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 24 03:01:41 blueplayer winbindd[887]: [2022/06/24 03:01:41.016633, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 24 03:01:41 blueplayer winbindd[887]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 24 03:01:41 blueplayer systemd[1]: Started Samba Winbind Daemon. Jun 24 03:01:41 blueplayer systemd[1]: Starting Samba SMB Daemon... Jun 24 03:01:41 blueplayer volumio[825]: info: Plugin folders cleanup Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning into folder /volumio/app/plugins/ Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category audio_interface Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category miscellanea Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category music_service Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category plugins.json Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category system_controller Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category user_interface Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning into folder /data/plugins/ Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category miscellanea Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category music_service Jun 24 03:01:41 blueplayer volumio[825]: info: Scanning category user_interface Jun 24 03:01:41 blueplayer volumio[825]: info: Plugin folders cleanup completed Jun 24 03:01:41 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:41 blueplayer volumio[825]: info: ----- Core plugins startup ---- Jun 24 03:01:41 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:41 blueplayer volumio[825]: info: Loading plugins from folder /volumio/app/plugins/ Jun 24 03:01:41 blueplayer volumio[825]: info: Adding plugin upnp to MyMusic Plugins Jun 24 03:01:41 blueplayer volumio[825]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 24 03:01:41 blueplayer volumio[825]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 24 03:01:41 blueplayer volumio[825]: info: Loading plugins from folder /data/plugins/ Jun 24 03:01:41 blueplayer volumio[825]: info: Loading plugin "system"... Jun 24 03:01:41 blueplayer volumio[825]: info: Loading plugin "appearance"... Jun 24 03:01:41 blueplayer winbindd[887]: [2022/06/24 03:01:41.396489, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jun 24 03:01:41 blueplayer winbindd[887]: res_names->count = 1, expected 2 Jun 24 03:01:41 blueplayer smbd[894]: [2022/06/24 03:01:41.418371, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 24 03:01:41 blueplayer systemd[1]: Started Samba SMB Daemon. Jun 24 03:01:41 blueplayer smbd[894]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 24 03:01:41 blueplayer systemd[1]: Reached target Multi-User System. Jun 24 03:01:41 blueplayer systemd[1]: Reached target Graphical Interface. Jun 24 03:01:41 blueplayer systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 24 03:01:41 blueplayer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 24 03:01:41 blueplayer systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 24 03:01:41 blueplayer systemd[1]: Startup finished in 7.795s (kernel) + 12.329s (userspace) = 20.124s. Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "network"... Jun 24 03:01:42 blueplayer volumio[825]: info: Refreshing Cached IP Addresses Jun 24 03:01:42 blueplayer sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 03:01:42 blueplayer sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:42 blueplayer sudo[905]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:42 blueplayer volumio[825]: info: Plugin peppy_screensaver is not enabled Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "touch_display"... Jun 24 03:01:42 blueplayer sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 03:01:42 blueplayer sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:42 blueplayer sudo[907]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:42 blueplayer sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 24 03:01:42 blueplayer sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:42 blueplayer volumio[825]: info: Applying required configuration parameters for plugin touch_display Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "services"... Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "alsa_controller"... Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "wizard"... Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "volumio_command_line_client"... Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "upnp"... Jun 24 03:01:42 blueplayer volumio[825]: info: [1656039702758] Starting Upmpd Daemon Jun 24 03:01:42 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "my_music"... Jun 24 03:01:42 blueplayer volumio[825]: info: Loading plugin "mpd"... Jun 24 03:01:43 blueplayer volumio[825]: info: Creating MPD Configuration file Jun 24 03:01:43 blueplayer volumio[825]: info: Loading plugin "upnp_browser"... Jun 24 03:01:43 blueplayer sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 03:01:43 blueplayer sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:43 blueplayer sudo[916]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:43 blueplayer sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 03:01:43 blueplayer sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:43 blueplayer systemd[1]: Stopping Music Player Daemon... Jun 24 03:01:43 blueplayer sh[383]: eth0: leased 192.168.1.118 for 86400 seconds Jun 24 03:01:43 blueplayer sh[383]: eth0: adding route to 192.168.1.0/24 Jun 24 03:01:43 blueplayer sh[383]: eth0: adding default route via 192.168.1.1 Jun 24 03:01:43 blueplayer dhcpcd[440]: eth0: leased 192.168.1.118 for 86400 seconds Jun 24 03:01:43 blueplayer avahi-daemon[469]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.118. Jun 24 03:01:43 blueplayer avahi-daemon[469]: New relevant interface eth0.IPv4 for mDNS. Jun 24 03:01:43 blueplayer dhcpcd[440]: eth0: adding route to 192.168.1.0/24 Jun 24 03:01:43 blueplayer avahi-daemon[469]: Registering new address record for 192.168.1.118 on eth0.IPv4. Jun 24 03:01:43 blueplayer dhcpcd[440]: eth0: adding default route via 192.168.1.1 Jun 24 03:01:43 blueplayer dnsmasq[819]: reading /etc/resolv.conf Jun 24 03:01:43 blueplayer dnsmasq[819]: using nameserver 192.168.1.1#53 Jun 24 03:01:43 blueplayer dnsmasq[819]: using nameserver 208.67.222.222#53 Jun 24 03:01:43 blueplayer dnsmasq[819]: using nameserver 208.67.220.220#53 Jun 24 03:01:43 blueplayer sh[383]: forked to background, child pid 941 Jun 24 03:01:43 blueplayer dhcpcd[440]: forked to background, child pid 941 Jun 24 03:01:43 blueplayer volumio[825]: info: Loading plugin "networkfs"... Jun 24 03:01:43 blueplayer sudo[964]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:43 blueplayer sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:43 blueplayer sudo[964]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:43 blueplayer sudo[966]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ssh.service Jun 24 03:01:43 blueplayer sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:43 blueplayer sudo[966]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:43 blueplayer sh[383]: dc:a6:32:6d:07:2f10000000b57d5c3a Jun 24 03:01:43 blueplayer sh[383]: OK Jun 24 03:01:43 blueplayer systemd[1]: mpd.service: Succeeded. Jun 24 03:01:43 blueplayer systemd[1]: Stopped Music Player Daemon. Jun 24 03:01:43 blueplayer sudo[977]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/V3.done Jun 24 03:01:43 blueplayer sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:43 blueplayer systemd[1]: Starting Music Player Daemon... Jun 24 03:01:43 blueplayer sudo[977]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:43 blueplayer sudo[980]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/touch /boot/AK4497 Jun 24 03:01:43 blueplayer sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:43 blueplayer sudo[980]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:43 blueplayer sh[383]: run-parts: /etc/network/if-up.d/net exited with return code 1 Jun 24 03:01:43 blueplayer sh[383]: ifup: failed to bring up eth0 Jun 24 03:01:43 blueplayer systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 24 03:01:43 blueplayer dhcpcd[941]: received SIGTERM, stopping Jun 24 03:01:43 blueplayer dhcpcd[941]: eth0: removing interface Jun 24 03:01:43 blueplayer dhcpcd[997]: dhcpcd already running on pid 941 (/var/run/dhcpcd-eth0.pid) Jun 24 03:01:43 blueplayer ifplugd(eth0)[716]: client: dhcpcd already running on pid 941 (/var/run/dhcpcd-eth0.pid) Jun 24 03:01:43 blueplayer ifplugd(eth0)[716]: client: ifup: failed to bring up eth0 Jun 24 03:01:43 blueplayer ifplugd(eth0)[716]: client: run-parts: /etc/ifplugd/action.d//ifupdown exited with return code 1 Jun 24 03:01:43 blueplayer dhcpcd[941]: dhcpcd exited Jun 24 03:01:43 blueplayer systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 24 03:01:43 blueplayer volumio[825]: info: Starting Udev Watcher for removable devices Jun 24 03:01:43 blueplayer ifplugd(eth0)[716]: Program execution failed, return value is 1. Jun 24 03:01:43 blueplayer mpd[1000]: /usr/bin/mpd: /usr/lib/arm-linux-gnueabihf/libcurl.so.4: no version information available (required by /usr/bin/mpd) Jun 24 03:01:44 blueplayer volumio[825]: info: Ignoring mount for partition: boot Jun 24 03:01:44 blueplayer volumio[825]: info: Ignoring mount for partition: volumio Jun 24 03:01:44 blueplayer volumio[825]: info: Ignoring mount for partition: volumio_data Jun 24 03:01:44 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "alarm-clock"... Jun 24 03:01:44 blueplayer kernel: fuse: init (API version 7.32) Jun 24 03:01:44 blueplayer systemd[1]: Mounting FUSE Control File System... Jun 24 03:01:44 blueplayer systemd[1]: Mounted FUSE Control File System. Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "airplay_emulation"... Jun 24 03:01:44 blueplayer volumio[825]: info: Starting Shairport Sync Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "last_100"... Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "webradio"... Jun 24 03:01:44 blueplayer mpd[1000]: Jun 24 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "i2s_dacs"... Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "volumiodiscovery"... Jun 24 03:01:44 blueplayer volumio[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 03:01:44 blueplayer node[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 03:01:44 blueplayer volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 03:01:44 blueplayer node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 03:01:44 blueplayer volumio[825]: *** WARNING *** For more information see Jun 24 03:01:44 blueplayer node[825]: *** WARNING *** For more information see Jun 24 03:01:44 blueplayer volumio[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 03:01:44 blueplayer node[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 03:01:44 blueplayer volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 03:01:44 blueplayer node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 03:01:44 blueplayer volumio[825]: *** WARNING *** For more information see Jun 24 03:01:44 blueplayer node[825]: *** WARNING *** For more information see Jun 24 03:01:44 blueplayer volumio[825]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 24 03:01:44 blueplayer volumio[825]: info: Discovery: Started advertising with name: Blueplayer Jun 24 03:01:44 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 03:01:44 blueplayer volumio[825]: info: Loading plugin "youtube2"... Jun 24 03:01:45 blueplayer ntpd[686]: Listen normally on 4 eth0 192.168.1.118:123 Jun 24 03:01:45 blueplayer ntpd[686]: new interface(s) found: waking up resolver Jun 24 03:01:45 blueplayer volumio-remote-updater[471]: [2022-06-24 03:01:45] [connect] Successful connection Jun 24 03:01:46 blueplayer sudo[910]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:47 blueplayer mpd[1000]: Jun 24 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 24 03:01:47 blueplayer mpd[1000]: Jun 24 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 24 03:01:47 blueplayer systemd[1]: Started Music Player Daemon. Jun 24 03:01:47 blueplayer sudo[918]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:47 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Jun 24 03:01:47 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 1. Jun 24 03:01:47 blueplayer systemd[1]: Stopped Sound check for Addon. Jun 24 03:01:47 blueplayer systemd[1]: Started Sound check for Addon. Jun 24 03:01:47 blueplayer bash[1038]: end Jun 24 03:01:48 blueplayer bash[1038]: endplay Jun 24 03:01:48 blueplayer systemd[1]: soundcheck.service: Succeeded. Jun 24 03:01:49 blueplayer volumio[825]: info: Loading plugin "ytcr"... Jun 24 03:01:50 blueplayer volumio[825]: info: Plugin now_playing is not enabled Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "outputs"... Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "albumart"... Jun 24 03:01:50 blueplayer volumio[825]: info: Plugin example_plugin is not enabled Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "inputs"... Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "updater_comm"... Jun 24 03:01:50 blueplayer volumio[825]: info: Plugin mpdemulation is not enabled Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "rest_api"... Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "websocket"... Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "RoonBridge"... Jun 24 03:01:50 blueplayer volumio[825]: Forking 3 albumart workers Jun 24 03:01:50 blueplayer volumio[825]: info: Applying required configuration parameters for plugin RoonBridge Jun 24 03:01:50 blueplayer volumio[825]: info: Loading plugin "spop"... Jun 24 03:01:51 blueplayer volumio[825]: Starting albumart workers Jun 24 03:01:51 blueplayer volumio[825]: Starting albumart workers Jun 24 03:01:51 blueplayer volumio[825]: Starting albumart workers Jun 24 03:01:51 blueplayer volumio[825]: info: Loading plugin "volspotconnect2"... Jun 24 03:01:52 blueplayer volumio[825]: info: Loading i18n strings for locale en Jun 24 03:01:52 blueplayer volumio[825]: Updating browse sources language Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::initPlayerControls Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: Express server listening on port 3000 Jun 24 03:01:52 blueplayer volumio[825]: [Metrics] WebUI: 12s 549.27ms Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::resetVolumioState Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::getcurrentVolume Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 03:01:52 blueplayer volumio[825]: info: MPD Permissions set Jun 24 03:01:52 blueplayer volumio[825]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jun 24 03:01:52 blueplayer volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jun 24 03:01:52 blueplayer volumio[825]: info: MPD running with PID1000 Jun 24 03:01:52 blueplayer volumio[825]: ,establishing connection Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: Reloading queue from file Jun 24 03:01:52 blueplayer volumio[825]: error: updateQueue error: null Jun 24 03:01:52 blueplayer volumio[825]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 24 03:01:52 blueplayer volumio[825]: info: VolumeController:: Volume=undefined Mute =false Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::updateTrackBlock Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrackBlock Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 03:01:52 blueplayer volumio[825]: info: Setting Device type: Raspberry PI Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::setRepeat null single undefined Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::setRandom null Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:01:52 blueplayer volumio[825]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jun 24 03:01:52 blueplayer kernel: cam-dummy-reg: disabling Jun 24 03:01:52 blueplayer kernel: cam1-reg: disabling Jun 24 03:01:52 blueplayer volumio[825]: info: Completed loading Core Plugins Jun 24 03:01:52 blueplayer volumio[825]: info: Preparing to generate the ALSA configuration file Jun 24 03:01:52 blueplayer volumio[825]: info: Discovery: adding b662bd65-bfdf-4535-a924-16a12794d28b Jun 24 03:01:52 blueplayer volumio[825]: info: mDNS: Found device Blueplayer Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:52 blueplayer volumio[825]: info: Discovery: this is already registered, b662bd65-bfdf-4535-a924-16a12794d28b Jun 24 03:01:52 blueplayer volumio[825]: info: mDNS: Found device Blueplayer Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:52 blueplayer volumio[825]: info: VolumeController:: Volume=undefined Mute =false Jun 24 03:01:52 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:01:52 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:01:52 blueplayer volumio[825]: info: Asound.conf file unchanged, so no further update is needed Jun 24 03:01:52 blueplayer volumio[825]: info: Output device has changed, restarting MPD Jun 24 03:01:52 blueplayer sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 03:01:52 blueplayer sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:52 blueplayer volumio[825]: info: Output device has changed, restarting Shairport Sync Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:52 blueplayer sudo[1115]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:52 blueplayer sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 03:01:52 blueplayer sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:52 blueplayer systemd[1]: Stopping Music Player Daemon... Jun 24 03:01:52 blueplayer volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 03:01:52 blueplayer volumio[825]: info: ___________ START PLUGINS ___________ Jun 24 03:01:52 blueplayer volumio[825]: info: Loading i18n strings for locale en Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 03:01:52 blueplayer volumio[825]: info: [1656039712768] CoreMusicLibrary::Adding element Media Servers Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 03:01:52 blueplayer volumio[825]: Cannot find translation for sourceMedia Servers Jun 24 03:01:52 blueplayer sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 24 03:01:52 blueplayer sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:52 blueplayer systemd[1]: Reloading. Jun 24 03:01:52 blueplayer volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:52 blueplayer volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 03:01:52 blueplayer volumio[825]: info: [1656039712885] CoreMusicLibrary::Adding element Last_100 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 03:01:52 blueplayer volumio[825]: Cannot find translation for sourceMedia Servers Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 03:01:52 blueplayer volumio[825]: info: [1656039712888] CoreMusicLibrary::Adding element Webradio Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 03:01:52 blueplayer volumio[825]: Cannot find translation for sourceMedia Servers Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 03:01:52 blueplayer volumio[825]: info: [1656039712908] CoreMusicLibrary::Adding element YouTube2 Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 03:01:52 blueplayer volumio[825]: Cannot find translation for sourceMedia Servers Jun 24 03:01:52 blueplayer volumio[825]: Cannot find translation for sourceYouTube2 Jun 24 03:01:52 blueplayer sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 24 03:01:52 blueplayer sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:52 blueplayer volumio[825]: [SpotifyConnect] Creating VLS config file Jun 24 03:01:52 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: [SpotifyConnect] Starting metadata listener Jun 24 03:01:53 blueplayer sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Jun 24 03:01:53 blueplayer sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer volumio[825]: info: Volumio Calling Home Jun 24 03:01:53 blueplayer sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jun 24 03:01:53 blueplayer sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer 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. Jun 24 03:01:53 blueplayer systemd[1]: Configuration file /lib/systemd/system/voltc.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 24 03:01:53 blueplayer systemd[1]: Configuration file /lib/systemd/system/soundcheck.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 24 03:01:53 blueplayer systemd[1]: Configuration file /lib/systemd/system/soundcheck.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jun 24 03:01:53 blueplayer sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 03:01:53 blueplayer sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer sudo[1158]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer volumio[825]: info: MPD Permissions set Jun 24 03:01:53 blueplayer volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 03:01:53 blueplayer sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer sudo[1161]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:01:53 blueplayer volumio[825]: info: Starting Shairport Sync Jun 24 03:01:53 blueplayer systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 24 03:01:53 blueplayer volumio[825]: info: Starting Shairport Sync Jun 24 03:01:53 blueplayer systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 24 03:01:53 blueplayer sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 03:01:53 blueplayer sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer volumio[825]: info: Starting Shairport Sync Jun 24 03:01:53 blueplayer sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 03:01:53 blueplayer sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 03:01:53 blueplayer sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 24 03:01:53 blueplayer sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:01:53 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:53 blueplayer sudo[1124]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: systemctl daemon-reload succeeded. Jun 24 03:01:53 blueplayer volumio[825]: info: Volumio called home Jun 24 03:01:53 blueplayer systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 24 03:01:53 blueplayer systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jun 24 03:01:53 blueplayer sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 24 03:01:53 blueplayer sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer systemd[1]: Started Volumio Kiosk. Jun 24 03:01:53 blueplayer systemd[1]: Started UPnP Renderer front-end to MPD. Jun 24 03:01:53 blueplayer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 24 03:01:53 blueplayer systemd[1]: Started Volspotconnect2 Daemon. Jun 24 03:01:53 blueplayer sudo[1177]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer sudo[1153]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer systemd[1]: Started Spotify Daemon. Jun 24 03:01:53 blueplayer sudo[1149]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer sudo[1133]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer volumio[825]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 24 03:01:53 blueplayer volumio[825]: [Metrics] SpotifyConnect: 0s 727.95ms Jun 24 03:01:53 blueplayer volumio[825]: info: Upmpdcli Daemon Started Jun 24 03:01:53 blueplayer volumio[825]: info: SpopD Daemon Started Jun 24 03:01:53 blueplayer systemd[1]: shairport-sync.service: Succeeded. Jun 24 03:01:53 blueplayer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 24 03:01:53 blueplayer sudo[1180]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: Volumio Kiosk started Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:01:53 blueplayer sudo[1173]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:01:53 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:53 blueplayer sudo[1172]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer sudo[1168]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer volumio[1186]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Jun 24 03:01:53 blueplayer volumio[1186]: Reading Config from "volspotify.toml" Jun 24 03:01:53 blueplayer volumio[1186]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 24 03:01:53 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:01:53 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:01:53 blueplayer volumio[825]: info: Shairport-Sync Started Jun 24 03:01:53 blueplayer volumio[825]: Error adding Membership: Error: addMembership EINVAL Jun 24 03:01:53 blueplayer volumio[825]: info: Shairport-Sync Started Jun 24 03:01:53 blueplayer volumio[825]: info: Shairport-Sync Started Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 24 03:01:53 blueplayer volumio[1188]: 2022-06-24 03:01:53 [SPTF] 03:01:53.872 I [offline_authorizer.cpp:297] Unable to login offline: no such user Jun 24 03:01:53 blueplayer volumio[1188]: 2022-06-24 03:01:53 [SPTF] 03:01:53.882 I [ap:1752] Connecting to AP ap.spotify.com:4070 Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jun 24 03:01:53 blueplayer volumio[1186]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jun 24 03:01:53 blueplayer sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 24 03:01:53 blueplayer sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:01:53 blueplayer volumio[1188]: 2022-06-24 03:01:53 [SPTF] 03:01:53.962 I [ap:1226] Connected to AP: 104.199.240.237:4070 Jun 24 03:01:53 blueplayer sudo[1219]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:53 blueplayer startx[1182]: X.Org X Server 1.20.4 Jun 24 03:01:53 blueplayer startx[1182]: X Protocol Version 11, Revision 0 Jun 24 03:01:53 blueplayer startx[1182]: Build Operating System: Linux 5.10.17-v8+ aarch64 Raspbian Jun 24 03:01:53 blueplayer startx[1182]: Current Operating System: Linux blueplayer 5.10.92-v7l+ #1514 SMP Mon Jan 17 17:38:03 GMT 2022 armv7l Jun 24 03:01:53 blueplayer startx[1182]: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:6D:07:2F vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jun 24 03:01:53 blueplayer startx[1182]: Build Date: 19 April 2021 03:23:36PM Jun 24 03:01:53 blueplayer startx[1182]: xorg-server 2:1.20.4-1+rpt3+deb10u3 (https://www.debian.org/support) Jun 24 03:01:53 blueplayer startx[1182]: Current version of pixman: 0.36.0 Jun 24 03:01:53 blueplayer startx[1182]: Before reporting problems, check http://wiki.x.org Jun 24 03:01:53 blueplayer startx[1182]: to make sure that you have the latest version. Jun 24 03:01:53 blueplayer startx[1182]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 24 03:01:53 blueplayer startx[1182]: (++) from command line, (!!) notice, (II) informational, Jun 24 03:01:53 blueplayer startx[1182]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 24 03:01:53 blueplayer startx[1182]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jun 24 03:01:53 2022 Jun 24 03:01:53 blueplayer startx[1182]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 24 03:01:53 blueplayer startx[1182]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: Touchscreen transformation matrix removed. Jun 24 03:01:53 blueplayer volumio[825]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 24 03:01:54 blueplayer volumio[825]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.159 E [ap:1694] AP Socket Error: Software caused connection abort (103) Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.160 E [ap:3953] Connection error: 112 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.160 I [ap:1752] Connecting to AP ap.spotify.com:443 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.238 I [ap:1226] Connected to AP: 104.199.240.237:443 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.464 E [ap:1694] AP Socket Error: Software caused connection abort (103) Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.464 E [ap:3953] Connection error: 112 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.464 I [ap:1752] Connecting to AP ap.spotify.com:80 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.551 I [ap:1226] Connected to AP: 104.199.240.237:80 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.801 E [ap:1694] AP Socket Error: Software caused connection abort (103) Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.801 E [ap:3953] Connection error: 112 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.802 I [ap:1752] Connecting to AP ap.spotify.com:4070 Jun 24 03:01:54 blueplayer volumio[1188]: 2022-06-24 03:01:54 [SPTF] 03:01:54.888 I [ap:1226] Connected to AP: 104.199.240.237:4070 Jun 24 03:01:54 blueplayer volumio[825]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.108 E [ap:1694] AP Socket Error: Software caused connection abort (103) Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.108 E [ap:3953] Connection error: 112 Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.108 I [ap:1752] Connecting to AP ap.spotify.com:443 Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.193 I [ap:1226] Connected to AP: 104.199.240.237:443 Jun 24 03:01:55 blueplayer volumio[825]: error: MPD error: Error: write EPIPE Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.365 E [ap:1694] AP Socket Error: Software caused connection abort (103) Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.365 E [ap:3953] Connection error: 112 Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.365 I [ap:1752] Connecting to AP ap.spotify.com:80 Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.524 I [ap:1226] Connected to AP: 104.199.240.237:80 Jun 24 03:01:55 blueplayer systemd[1]: mpd.service: Succeeded. Jun 24 03:01:55 blueplayer systemd[1]: Stopped Music Player Daemon. Jun 24 03:01:55 blueplayer systemd[1]: Starting Music Player Daemon... Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.850 E [ap:1694] AP Socket Error: Software caused connection abort (103) Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [SPTF] 03:01:55.850 E [ap:3953] Connection error: 112 Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [WARN] Login failed: Cannot connect to Spotify Jun 24 03:01:55 blueplayer volumio[1188]: 2022-06-24 03:01:55 [ERR ] Could not get the playlist container. Jun 24 03:01:55 blueplayer systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP Jun 24 03:01:55 blueplayer systemd[1]: spop.service: Failed with result 'signal'. Jun 24 03:01:55 blueplayer mpd[1284]: /usr/bin/mpd: /usr/lib/arm-linux-gnueabihf/libcurl.so.4: no version information available (required by /usr/bin/mpd) Jun 24 03:01:56 blueplayer mpd[1284]: Jun 24 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 03:01:58 blueplayer volumio[825]: info: Connecting to daemon Jun 24 03:01:58 blueplayer volumio[825]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Jun 24 03:01:58 blueplayer volumio[825]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Jun 24 03:01:58 blueplayer volumio[825]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jun 24 03:01:58 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 03:01:58 blueplayer volumio[825]: Cannot find translation for sourceMedia Servers Jun 24 03:01:58 blueplayer volumio[825]: Cannot find translation for sourceYouTube2 Jun 24 03:01:58 blueplayer mpd[1284]: Jun 24 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 24 03:01:58 blueplayer mpd[1284]: Jun 24 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 24 03:01:58 blueplayer systemd[1]: Started Music Player Daemon. Jun 24 03:01:58 blueplayer volumio[825]: Upnp client error: Error: This socket has been ended by the other party Jun 24 03:01:58 blueplayer sudo[1118]: pam_unix(sudo:session): session closed for user root Jun 24 03:01:58 blueplayer volumio[825]: error: updateQueue error: null Jun 24 03:01:59 blueplayer volumio[1183]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 24 03:01:59 blueplayer volumio[825]: [yt-cast-receiver] Listening on port 8098 Jun 24 03:01:59 blueplayer volumio[825]: info: Completed starting Core Plugins Jun 24 03:01:59 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:59 blueplayer volumio[825]: info: ----- MyVolumio plugins startup ---- Jun 24 03:01:59 blueplayer volumio[825]: info: ------------------------------------------- Jun 24 03:01:59 blueplayer volumio[825]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 24 03:01:59 blueplayer dbus-daemon[457]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.16' (uid=1000 pid=1272 comm="/usr/lib/chromium-browser/chromium-browser-v7 --en") Jun 24 03:01:59 blueplayer systemd[1]: Starting Bluetooth service... Jun 24 03:01:59 blueplayer bluetoothd[1356]: Bluetooth daemon 5.50 Jun 24 03:01:59 blueplayer kernel: Bluetooth: Core ver 2.22 Jun 24 03:01:59 blueplayer kernel: NET: Registered protocol family 31 Jun 24 03:01:59 blueplayer kernel: Bluetooth: HCI device and connection manager initialized Jun 24 03:01:59 blueplayer kernel: Bluetooth: HCI socket layer initialized Jun 24 03:01:59 blueplayer kernel: Bluetooth: L2CAP socket layer initialized Jun 24 03:01:59 blueplayer kernel: Bluetooth: SCO socket layer initialized Jun 24 03:01:59 blueplayer bluetoothd[1356]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 24 03:01:59 blueplayer dbus-daemon[457]: [system] Successfully activated service 'org.bluez' Jun 24 03:01:59 blueplayer systemd[1]: Started Bluetooth service. Jun 24 03:01:59 blueplayer bluetoothd[1356]: Starting SDP server Jun 24 03:01:59 blueplayer bluetoothd[1356]: Excluding (cli) sap Jun 24 03:01:59 blueplayer kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 24 03:01:59 blueplayer kernel: Bluetooth: BNEP filters: protocol multicast Jun 24 03:01:59 blueplayer kernel: Bluetooth: BNEP socket layer initialized Jun 24 03:01:59 blueplayer dbus-daemon[457]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.17' (uid=0 pid=1356 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 24 03:01:59 blueplayer bluetoothd[1356]: Bluetooth management interface 1.18 initialized Jun 24 03:01:59 blueplayer systemd[1]: Starting Hostname Service... Jun 24 03:02:00 blueplayer volumio[825]: error: updateQueue error: null Jun 24 03:02:00 blueplayer dbus-daemon[457]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 24 03:02:00 blueplayer systemd[1]: Started Hostname Service. Jun 24 03:02:00 blueplayer volumio-remote-updater[471]: [2022-06-24 03:02:00] [connect] Successful connection Jun 24 03:02:00 blueplayer volumio-remote-updater[471]: [2022-06-24 03:02:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1656039720 101 Jun 24 03:02:00 blueplayer volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jun 24 03:02:01 blueplayer systemd[1]: systemd-fsckd.service: Succeeded. Jun 24 03:02:03 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Jun 24 03:02:03 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 2. Jun 24 03:02:03 blueplayer systemd[1]: Stopped Sound check for Addon. Jun 24 03:02:03 blueplayer systemd[1]: Started Sound check for Addon. Jun 24 03:02:03 blueplayer bash[1461]: end Jun 24 03:02:03 blueplayer bash[1461]: endplay Jun 24 03:02:03 blueplayer systemd[1]: soundcheck.service: Succeeded. Jun 24 03:02:03 blueplayer volumio[1183]: .............................................++++ Jun 24 03:02:03 blueplayer volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 4 Jun 24 03:02:04 blueplayer volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Jun 24 03:02:05 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:02:05 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:05 blueplayer volumio[825]: info: Listing playlists Jun 24 03:02:05 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetQueue Jun 24 03:02:05 blueplayer volumio[825]: info: CoreStateMachine::getQueue Jun 24 03:02:05 blueplayer volumio[825]: info: CorePlayQueue::getQueue Jun 24 03:02:05 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 24 03:02:05 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 24 03:02:05 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 24 03:02:05 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 24 03:02:06 blueplayer volumio[825]: info: VolumeController::SetAlsaVolume0 Jun 24 03:02:06 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:02:06 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:06 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:06 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:06 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 03:02:06 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:06 blueplayer volumio[825]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jun 24 03:02:07 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 24 03:02:18 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Jun 24 03:02:18 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 3. Jun 24 03:02:18 blueplayer systemd[1]: Stopped Sound check for Addon. Jun 24 03:02:18 blueplayer systemd[1]: Started Sound check for Addon. Jun 24 03:02:18 blueplayer bash[1652]: end Jun 24 03:02:18 blueplayer bash[1652]: endplay Jun 24 03:02:18 blueplayer systemd[1]: soundcheck.service: Succeeded. Jun 24 03:02:19 blueplayer volumio[825]: error: MyVolumio Plugin failed to start in a timely fashion Jun 24 03:02:19 blueplayer volumio[825]: info: BOOT COMPLETED Jun 24 03:02:19 blueplayer volumio[825]: [Metrics] CommandRouter: 38s 919.21ms Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::volumiosetStartupVolume Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 03:02:19 blueplayer volumio[825]: info: VolumeController:: Setting startup Volume 15 Jun 24 03:02:19 blueplayer volumio[825]: info: VolumeController::SetAlsaVolume15 Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::Close All Modals sent Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::Close All Modals sent Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::volumioGetState Jun 24 03:02:19 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:19 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:19 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 03:02:19 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:19 blueplayer volumio[825]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jun 24 03:02:20 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 24 03:02:20 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 24 03:02:20 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 24 03:02:22 blueplayer volumio[1183]: .......................................................................................................................................................................................................................++++ Jun 24 03:02:22 blueplayer volumio[1183]: e is 65537 (0x010001) Jun 24 03:02:22 blueplayer volumio[1183]: writing RSA key Jun 24 03:02:22 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 03:02:22 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 03:02:30 blueplayer systemd[1]: systemd-hostnamed.service: Succeeded. Jun 24 03:02:33 blueplayer systemd[1]: soundcheck.service: Service RestartSec=15s expired, scheduling restart. Jun 24 03:02:33 blueplayer systemd[1]: soundcheck.service: Scheduled restart job, restart counter is at 4. Jun 24 03:02:33 blueplayer systemd[1]: Stopped Sound check for Addon. Jun 24 03:02:33 blueplayer systemd[1]: Started Sound check for Addon. Jun 24 03:02:33 blueplayer bash[1680]: end Jun 24 03:02:33 blueplayer bash[1680]: endplay Jun 24 03:02:33 blueplayer systemd[1]: soundcheck.service: Succeeded. Jun 24 03:02:40 blueplayer ntpd[686]: Soliciting pool server 202.28.116.236 Jun 24 03:02:41 blueplayer ntpd[686]: Soliciting pool server 202.12.97.45 Jun 24 03:02:42 blueplayer ntpd[686]: Soliciting pool server 122.155.180.231 Jun 24 03:02:42 blueplayer ntpd[686]: Soliciting pool server 124.109.2.169 Jun 24 03:02:42 blueplayer ntpd[686]: Soliciting pool server 203.131.222.11 Jun 24 03:02:43 blueplayer ntpd[686]: Soliciting pool server 2001:deb:0:4070::33 Jun 24 03:02:43 blueplayer ntpd[686]: Soliciting pool server 203.159.70.33 Jun 24 03:02:45 blueplayer volumio[825]: info: Airplay started streaming, receiving metadatas Jun 24 03:02:45 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:45 blueplayer volumio[825]: info: CoreCommandRouter::volumioStop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::stop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer volumio[825]: verbose: UNSET VOLATILE Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer volumio[825]: info: Airplay started streaming, receiving metadatas Jun 24 03:02:45 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:45 blueplayer volumio[825]: info: CoreCommandRouter::volumioStop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::stop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer volumio[825]: verbose: UNSET VOLATILE Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer volumio[825]: info: Airplay started streaming, receiving metadatas Jun 24 03:02:45 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:45 blueplayer volumio[825]: info: CoreCommandRouter::volumioStop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::stop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer volumio[825]: verbose: UNSET VOLATILE Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer shairport-sync[1199]: /usr/bin/curl: /usr/lib/arm-linux-gnueabihf/libcurl.so.4: no version information available (required by /usr/bin/curl) Jun 24 03:02:45 blueplayer shairport-sync[1199]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 24 03:02:45 blueplayer shairport-sync[1199]: Dload Upload Total Spent Left Speed Jun 24 03:02:45 blueplayer volumio[825]: info: Jun 24 03:02:45 blueplayer volumio[825]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jun 24 03:02:45 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jun 24 03:02:45 blueplayer volumio[825]: info: Airplay playback start Jun 24 03:02:45 blueplayer volumio[825]: info: CorePlayQueue::getTrack 0 Jun 24 03:02:45 blueplayer volumio[825]: info: CoreCommandRouter::volumioStop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::stop Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer volumio[825]: verbose: UNSET VOLATILE Jun 24 03:02:45 blueplayer volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 03:02:45 blueplayer shairport-sync[1199]: [158B blob data] Jun 24 03:02:46 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:46 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:46 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 03:02:46 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:46 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , systemVstop Jun 24 03:02:46 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , gmeVstop Jun 24 03:02:46 blueplayer sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop systemvt.service Jun 24 03:02:47 blueplayer sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop gmrender.service Jun 24 03:02:47 blueplayer sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer sudo[1705]: pam_unix(sudo:session): session closed for user root Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer sudo[1707]: pam_unix(sudo:session): session closed for user root Jun 24 03:02:47 blueplayer volumio[825]: error: Error: Command failed: /usr/bin/sudo /bin/systemctl stop gmrender.service Jun 24 03:02:47 blueplayer volumio[825]: Failed to stop gmrender.service: Unit gmrender.service not loaded. Jun 24 03:02:47 blueplayer volumio[825]: error: Error: Command failed: /usr/bin/sudo /bin/systemctl stop systemvt.service Jun 24 03:02:47 blueplayer volumio[825]: Failed to stop systemvt.service: Unit systemvt.service not loaded. Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::servicePushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreStateMachine::pushState Jun 24 03:02:47 blueplayer volumio[825]: info: CoreCommandRouter::volumioPushState Jun 24 03:02:48 blueplayer volumio[825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 03:02:48 blueplayer volumio[825]: Error: certificate is not yet valid Jun 24 03:02:48 blueplayer volumio[825]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jun 24 03:02:48 blueplayer volumio[825]: at TLSSocket.emit (events.js:315:20) Jun 24 03:02:48 blueplayer volumio[825]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jun 24 03:02:48 blueplayer volumio[825]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jun 24 03:02:48 blueplayer volumio[825]: code: 'CERT_NOT_YET_VALID' Jun 24 03:02:48 blueplayer volumio[825]: } Jun 24 03:02:48 blueplayer volumio[825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 03:02:48 blueplayer sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-06-24 03:01 Jun 24 03:02:48 blueplayer sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"