-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2022-12-24 04:20:58 UTC. -- Dec 24 04:19:37 hifi kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 24 04:19:37 hifi initramfs: Booting Volumio for BCM2711 Dec 24 04:19:37 hifi initramfs: This script mounts rootfs RO with an overlay RW layer. Dec 24 04:19:37 hifi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 24 04:19:37 hifi initramfs: IMGPART=/dev/mmcblk0p2 Dec 24 04:19:37 hifi initramfs: IMGFILE=/volumio_current.sqsh Dec 24 04:19:37 hifi initramfs: Boot delay (except first time) will be 5 seconds Dec 24 04:19:37 hifi initramfs: /dev/mmcblk0p2: Dec 24 04:19:37 hifi kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 24 04:19:37 hifi kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Dec 24 04:19:37 hifi initramfs: Doing a 5 second delay here to give kernel load a headstart Dec 24 04:19:37 hifi kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Dec 24 04:19:37 hifi kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 24 04:19:37 hifi kernel: usb 1-1: Product: USB2.0 Hub Dec 24 04:19:37 hifi kernel: hub 1-1:1.0: USB hub found Dec 24 04:19:37 hifi kernel: hub 1-1:1.0: 4 ports detected Dec 24 04:19:37 hifi kernel: usb 1-1.3: new high-speed USB device number 3 using xhci_hcd Dec 24 04:19:37 hifi kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=86ef, bcdDevice= 3.12 Dec 24 04:19:37 hifi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 24 04:19:37 hifi kernel: usb 1-1.3: Product: DENAFRIPS USB Audio V3.12 Dec 24 04:19:37 hifi kernel: usb 1-1.3: Manufacturer: DENAFRIPS Dec 24 04:19:37 hifi initramfs: Checking for USB updates Dec 24 04:19:37 hifi initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Dec 24 04:19:37 hifi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 24 04:19:37 hifi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 24 04:19:37 hifi kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 24 04:19:37 hifi kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Dec 24 04:19:37 hifi initramfs: With Option: Dec 24 04:19:37 hifi initramfs: VOLUMIO_VERSION="3.396" Dec 24 04:19:37 hifi initramfs: Finish initramfs, continue booting Volumio Dec 24 04:19:37 hifi systemd[1]: System time before build time, advancing clock. Dec 24 04:19:37 hifi kernel: NET: Registered protocol family 10 Dec 24 04:19:37 hifi kernel: Segment Routing with IPv6 Dec 24 04:19:37 hifi 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) Dec 24 04:19:37 hifi systemd[1]: Detected architecture arm. Dec 24 04:19:37 hifi systemd[1]: Set hostname to . Dec 24 04:19:37 hifi 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. Dec 24 04:19:37 hifi kernel: random: systemd: uninitialized urandom read (16 bytes read) Dec 24 04:19:37 hifi kernel: random: systemd: uninitialized urandom read (16 bytes read) Dec 24 04:19:37 hifi kernel: random: systemd: uninitialized urandom read (16 bytes read) Dec 24 04:19:37 hifi systemd[1]: Listening on Journal Socket (/dev/log). Dec 24 04:19:37 hifi systemd[1]: Reached target System Time Synchronized. Dec 24 04:19:37 hifi systemd[1]: Listening on Journal Audit Socket. Dec 24 04:19:37 hifi systemd[1]: Listening on RPCbind Server Activation Socket. Dec 24 04:19:37 hifi systemd[1]: Listening on fsck to fsckd communication Socket. Dec 24 04:19:37 hifi systemd[1]: Listening on udev Kernel Socket. Dec 24 04:19:37 hifi kernel: i2c /dev entries driver Dec 24 04:19:37 hifi systemd-journald[191]: Journal started Dec 24 04:19:37 hifi systemd-journald[191]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Feb 14 10:11:59 hifi systemd-modules-load[190]: Inserted module 'i2c_dev' Dec 24 04:19:37 hifi fake-hwclock[197]: Sat Dec 24 04:19:37 UTC 2022 Dec 24 04:19:37 hifi systemd[1]: Started Load/Save Random Seed. Dec 24 04:19:37 hifi systemd[1]: dynamicswap.service: Succeeded. Dec 24 04:19:37 hifi systemd[1]: Started Create System Users. Dec 24 04:19:37 hifi systemd[1]: Starting Create Static Device Nodes in /dev... Dec 24 04:19:37 hifi systemd[1]: Started Create Static Device Nodes in /dev. Dec 24 04:19:37 hifi systemd[1]: Reached target Local File Systems (Pre). Dec 24 04:19:37 hifi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 24 04:19:37 hifi systemd[1]: Mounting /var/log... Dec 24 04:19:37 hifi systemd[1]: Mounting /var/spool/cups... Dec 24 04:19:37 hifi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 24 04:19:37 hifi systemd[1]: Mounting /tmp... Dec 24 04:19:37 hifi systemd[1]: Starting udev Kernel Device Manager... Dec 24 04:19:37 hifi systemd[1]: Started udev Coldplug all Devices. Dec 24 04:19:37 hifi systemd[1]: Mounted /var/log. Dec 24 04:19:37 hifi systemd[1]: Mounted /var/spool/cups. Dec 24 04:19:37 hifi systemd[1]: Mounted /tmp. Dec 24 04:19:37 hifi systemd[1]: Mounting /var/spool/cups/tmp... Dec 24 04:19:37 hifi systemd[1]: Starting Flush Journal to Persistent Storage... Dec 24 04:19:37 hifi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 24 04:19:37 hifi systemd[1]: Mounted /var/spool/cups/tmp. Dec 24 04:19:37 hifi systemd-udevd[220]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 24 04:19:37 hifi systemd-journald[191]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Dec 24 04:19:37 hifi systemd[1]: Started Flush Journal to Persistent Storage. Dec 24 04:19:37 hifi systemd[1]: Started udev Kernel Device Manager. Dec 24 04:19:37 hifi systemd[1]: Starting Show Plymouth Boot Screen... Dec 24 04:19:37 hifi systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). Dec 24 04:19:37 hifi systemd[1]: Started Show Plymouth Boot Screen. Dec 24 04:19:37 hifi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 24 04:19:37 hifi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 24 04:19:37 hifi systemd[1]: Reached target Paths. Dec 24 04:19:37 hifi systemd[1]: Reached target Local Encrypted Volumes. Dec 24 04:19:37 hifi kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Dec 24 04:19:37 hifi kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Dec 24 04:19:37 hifi kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Dec 24 04:19:37 hifi kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Dec 24 04:19:37 hifi systemd-udevd[250]: Using default interface naming scheme 'v240'. Dec 24 04:19:37 hifi kernel: mc: Linux media interface: v0.10 Dec 24 04:19:37 hifi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:37 hifi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 24 04:19:37 hifi kernel: [vc_sm_connected_init]: start Dec 24 04:19:38 hifi kernel: [vc_sm_connected_init]: installed successfully Dec 24 04:19:38 hifi systemd-udevd[245]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 24 04:19:38 hifi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 24 04:19:38 hifi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 24 04:19:38 hifi systemd-udevd[250]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 24 04:19:38 hifi kernel: videodev: Linux video capture interface: v2.00 Dec 24 04:19:38 hifi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 24 04:19:38 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 24 04:19:38 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 24 04:19:38 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 24 04:19:38 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 24 04:19:38 hifi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 24 04:19:39 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 24 04:19:39 hifi kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 24 04:19:39 hifi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 24 04:19:39 hifi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 24 04:19:39 hifi systemd[1]: Found device /dev/mmcblk0p1. Dec 24 04:19:39 hifi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 24 04:19:39 hifi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 24 04:19:40 hifi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 24 04:19:40 hifi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 24 04:19:40 hifi kernel: usbcore: registered new interface driver brcmfmac Dec 24 04:19:40 hifi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in FUSE Control File System being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 24 04:19:40 hifi systemd[1]: Starting File System Check on /dev/mmcblk0p1... Dec 24 04:19:40 hifi kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Dec 24 04:19:40 hifi kernel: usb 1-1.3: 1:2 : unsupported format bits 0x100000000 Dec 24 04:19:40 hifi kernel: usb 1-1.3: Warning! Unlikely big volume range (=512), cval->res is probably wrong. Dec 24 04:19:40 hifi kernel: usb 1-1.3: [10] FU [PCM Playback Volume] ch = 2, val = -16384/0/32 Dec 24 04:19:40 hifi kernel: usbcore: registered new interface driver snd-usb-audio Dec 24 04:19:40 hifi systemd[1]: Found device /sys/subsystem/net/devices/eth0. Dec 24 04:19:40 hifi systemd-fsck[364]: fsck.fat 4.1 (2017-01-24) Dec 24 04:19:40 hifi systemd-fsck[364]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Dec 24 04:19:40 hifi systemd-fsck[364]: Automatically removing dirty bit. Dec 24 04:19:40 hifi systemd-fsck[364]: Performing changes. Dec 24 04:19:40 hifi systemd-fsck[364]: /dev/mmcblk0p1: 305 files, 109530/123044 clusters Dec 24 04:19:40 hifi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 24 04:19:40 hifi systemd[1]: Started File System Check Daemon to report status. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in FUSE Control File System being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 24 04:19:40 hifi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 24 04:19:40 hifi systemd[1]: Started File System Check on /dev/mmcblk0p1. Dec 24 04:19:40 hifi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 24 04:19:40 hifi systemd[1]: Mounting /boot... Dec 24 04:19:40 hifi 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 Dec 24 04:19:40 hifi systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 24 04:19:41 hifi systemd[1]: Mounted /boot. Dec 24 04:19:41 hifi systemd[1]: Reached target Local File Systems. Dec 24 04:19:41 hifi systemd[1]: Starting Preprocess NFS configuration... Dec 24 04:19:41 hifi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 24 04:19:41 hifi systemd[1]: Started ifup for eth0. Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 24 04:19:41 hifi systemd[1]: Starting Create Volatile Files and Directories... Dec 24 04:19:41 hifi systemd-udevd[254]: Using default interface naming scheme 'v240'. Dec 24 04:19:41 hifi systemd-udevd[254]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 24 04:19:41 hifi systemd[1]: nfs-config.service: Succeeded. Dec 24 04:19:41 hifi systemd[1]: Started Preprocess NFS configuration. Dec 24 04:19:41 hifi systemd[1]: plymouth-read-write.service: Succeeded. Dec 24 04:19:41 hifi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 24 04:19:41 hifi systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 24 04:19:41 hifi systemd[1]: Reached target NFS client services. Dec 24 04:19:41 hifi systemd[1]: Started Create Volatile Files and Directories. Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 24 04:19:41 hifi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 24 04:19:41 hifi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 24 04:19:41 hifi systemd[1]: Starting RPC bind portmap service... Dec 24 04:19:41 hifi systemd[1]: Started Load/Save RF Kill Switch Status. Dec 24 04:19:41 hifi systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 24 04:19:41 hifi systemd[1]: Reached target System Initialization. Dec 24 04:19:41 hifi systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 24 04:19:41 hifi systemd[1]: apt-daily.timer: Not using persistent file timestamp Mon 2024-05-06 05:24:25 UTC as it is in the future. Dec 24 04:19:41 hifi systemd[1]: Started Daily apt download activities. Dec 24 04:19:41 hifi systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Mon 2024-05-06 06:54:01 UTC as it is in the future. Dec 24 04:19:41 hifi systemd[1]: Started Daily apt upgrade and clean activities. Dec 24 04:19:41 hifi systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 24 04:19:41 hifi systemd[1]: Reached target Timers. Dec 24 04:19:41 hifi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 24 04:19:41 hifi systemd[1]: Listening on mpd.socket. Dec 24 04:19:41 hifi systemd[1]: Listening on triggerhappy.socket. Dec 24 04:19:41 hifi systemd[1]: Reached target Sockets. Dec 24 04:19:41 hifi systemd[1]: Reached target Basic System. Dec 24 04:19:41 hifi systemd[1]: Started UPnP Renderer front-end to MPD. Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 24 04:19:41 hifi systemd[1]: Starting Wireless Services... Dec 24 04:19:41 hifi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 24 04:19:41 hifi kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 24 04:19:41 hifi kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 24 04:19:41 hifi systemd[1]: Started D-Bus System Message Bus. Dec 24 04:19:41 hifi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 24 04:19:41 hifi systemd[1]: Started Volumio Iptables Module. Dec 24 04:19:41 hifi systemd[1]: Starting WPA supplicant... Dec 24 04:19:41 hifi systemd[1]: Started Manage Sound Card State (restore and store). Dec 24 04:19:41 hifi systemd[1]: Starting Save/Restore Sound Card State... Dec 24 04:19:41 hifi alsactl[425]: alsactl 1.1.8 daemon started Dec 24 04:19:41 hifi systemd[1]: Starting dhcpcd on all interfaces... Dec 24 04:19:41 hifi systemd[1]: Starting Login Service... Dec 24 04:19:41 hifi systemd[1]: Started volumio-remote-updater.service. Dec 24 04:19:41 hifi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 24 04:19:41 hifi systemd[1]: Starting triggerhappy global hotkey daemon... Dec 24 04:19:41 hifi systemd[1]: Started RPC bind portmap service. Dec 24 04:19:41 hifi systemd[1]: Started Save/Restore Sound Card State. Dec 24 04:19:41 hifi thd[433]: Unable to parse trigger line: Dec 24 04:19:41 hifi thd[433]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 24 04:19:41 hifi thd[433]: Unable to parse trigger line: Dec 24 04:19:41 hifi thd[433]: Unable to parse trigger line: Dec 24 04:19:41 hifi thd[433]: Found socket passed from systemd Dec 24 04:19:42 hifi systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 24 04:19:42 hifi systemd[1]: Started triggerhappy global hotkey daemon. Dec 24 04:19:42 hifi systemd[1]: Started ifup for wlan0. Dec 24 04:19:42 hifi systemd[1]: Reached target Sound Card. Dec 24 04:19:42 hifi systemd[1]: Reached target RPC Port Mapper. Dec 24 04:19:42 hifi systemd[1]: Reached target Remote File Systems (Pre). Dec 24 04:19:42 hifi systemd[1]: Reached target Remote File Systems. Dec 24 04:19:42 hifi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 24 04:19:42 hifi sh[378]: eth0=eth0 Dec 24 04:19:42 hifi kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 24 04:19:42 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 24 04:19:42 hifi avahi-daemon[419]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 24 04:19:42 hifi avahi-daemon[419]: Successfully dropped root privileges. Dec 24 04:19:42 hifi avahi-daemon[419]: avahi-daemon 0.7 starting up. Dec 24 04:19:42 hifi systemd-logind[431]: New seat seat0. Dec 24 04:19:42 hifi avahi-daemon[419]: Successfully called chroot(). Dec 24 04:19:42 hifi avahi-daemon[419]: Successfully dropped remaining capabilities. Dec 24 04:19:42 hifi avahi-daemon[419]: Loading service file /services/volumio.service. Dec 24 04:19:42 hifi avahi-daemon[419]: Network interface enumeration completed. Dec 24 04:19:42 hifi avahi-daemon[419]: Server startup complete. Host name is hifi.local. Local service cookie is 3395976679. Dec 24 04:19:42 hifi avahi-daemon[419]: Service "HiFi" (/services/volumio.service) successfully established. Dec 24 04:19:42 hifi dhcpcd[427]: dev: loaded udev Dec 24 04:19:42 hifi systemd[1]: Started Login Service. Dec 24 04:19:42 hifi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 24 04:19:42 hifi sh[447]: wlan0=wlan0 Dec 24 04:19:42 hifi kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 24 04:19:42 hifi dhcpcd[427]: forked to background, child pid 510 Dec 24 04:19:42 hifi systemd[1]: Started dhcpcd on all interfaces. Dec 24 04:19:43 hifi loadcpufreq[448]: Loading cpufreq kernel modules...done (none). Dec 24 04:19:43 hifi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 24 04:19:43 hifi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 24 04:19:43 hifi cpufrequtils[536]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 24 04:19:43 hifi systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 24 04:19:43 hifi dhcpcd-run-hooks[565]: wlan0: starting wpa_supplicant Dec 24 04:19:44 hifi systemd[1]: Started WPA supplicant. Dec 24 04:19:44 hifi wpa_supplicant[422]: Successfully initialized wpa_supplicant Dec 24 04:19:44 hifi volumio-remote-updater[432]: Error: No active session Dec 24 04:19:44 hifi volumio-remote-updater[432]: [2022-12-24 04:19:44] [info] asio async_connect error: system:111 (Connection refused) Dec 24 04:19:44 hifi volumio-remote-updater[432]: [2022-12-24 04:19:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 24 04:19:44 hifi volumio-remote-updater[432]: [2022-12-24 04:19:44] [error] handle_connect error: Underlying Transport Error Dec 24 04:19:44 hifi volumio[411]: Could not open config: /tmp/upmpdcli.conf Dec 24 04:19:44 hifi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 24 04:19:44 hifi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 24 04:19:44 hifi systemd[1]: iptables.service: Succeeded. Dec 24 04:19:44 hifi systemd-udevd[250]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Dec 24 04:19:44 hifi haveged[399]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 24 04:19:44 hifi haveged[399]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 24 04:19:44 hifi haveged[399]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99906 Dec 24 04:19:44 hifi haveged[399]: haveged: fills: 0, generated: 0 Dec 24 04:19:44 hifi kernel: random: crng init done Dec 24 04:19:44 hifi kernel: random: 7 urandom warning(s) missed due to ratelimiting Dec 24 04:19:44 hifi systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 24 04:19:44 hifi systemd[1]: Starting Raise network interfaces... Dec 24 04:19:44 hifi dhcpcd[510]: wlan0: connected to Access Point `' Dec 24 04:19:44 hifi dhcpcd[510]: eth0: waiting for carrier Dec 24 04:19:44 hifi dhcpcd[510]: wlan0: waiting for carrier Dec 24 04:19:44 hifi systemd[1]: Started Raise network interfaces. Dec 24 04:19:44 hifi systemd[1]: Reached target Network. Dec 24 04:19:44 hifi systemd[1]: Starting Network Time Service... Dec 24 04:19:44 hifi systemd[1]: Starting Permit User Sessions... Dec 24 04:19:44 hifi systemd[1]: Reached target Network is Online. Dec 24 04:19:44 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 24 04:19:44 hifi systemd[1]: Starting LSB: Brings up/down network automatically... Dec 24 04:19:44 hifi systemd[1]: Starting /etc/rc.local Compatibility... Dec 24 04:19:44 hifi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 24 04:19:44 hifi systemd[1]: Starting Samba NMB Daemon... Dec 24 04:19:44 hifi systemd[1]: Starting Music Player Daemon... Dec 24 04:19:44 hifi systemd[1]: Started Permit User Sessions. Dec 24 04:19:44 hifi systemd[1]: Started /etc/rc.local Compatibility. Dec 24 04:19:44 hifi systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 24 04:19:44 hifi systemd[1]: Starting Hold until boot process finishes up... Dec 24 04:19:44 hifi systemd[1]: Received SIGRTMIN+21 from PID 240 (plymouthd). Dec 24 04:19:44 hifi systemd[1]: Received SIGRTMIN+21 from PID 240 (plymouthd). Dec 24 04:19:44 hifi systemd[1]: plymouth-quit.service: Succeeded. Dec 24 04:19:44 hifi systemd[1]: Started Terminate Plymouth Boot Screen. Dec 24 04:19:44 hifi systemd[1]: plymouth-start.service: Succeeded. Dec 24 04:19:44 hifi systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 24 04:19:44 hifi systemd[1]: Started Hold until boot process finishes up. Dec 24 04:19:44 hifi systemd[1]: Started Getty on tty1. Dec 24 04:19:44 hifi systemd[1]: Reached target Login Prompts. Dec 24 04:19:44 hifi ifplugd(eth0)[677]: ifplugd 0.28 initializing. Dec 24 04:19:44 hifi ifplugd(eth0)[677]: Using interface eth0/E4:5F:01:59:D7:E6 with driver (version: 5.10.92-v7l+) Dec 24 04:19:44 hifi ifplugd(eth0)[677]: Using detection mode: SIOCETHTOOL Dec 24 04:19:44 hifi ifplugd(eth0)[677]: Initialization complete, link beat not detected. Dec 24 04:19:44 hifi ifplugd[654]: Network Interface Plugging Daemon...start eth0...done. Dec 24 04:19:44 hifi systemd[1]: Started LSB: Brings up/down network automatically. Dec 24 04:19:44 hifi ntpd[651]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 24 04:19:44 hifi ntpd[651]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 24 04:19:44 hifi systemd[1]: Started Network Time Service. Dec 24 04:19:44 hifi ntpd[683]: proto: precision = 0.796 usec (-20) Dec 24 04:19:44 hifi ntpd[683]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 24 04:19:44 hifi ntpd[683]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 24 04:19:44 hifi ntpd[683]: Listen and drop on 0 v6wildcard [::]:123 Dec 24 04:19:44 hifi ntpd[683]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 24 04:19:44 hifi ntpd[683]: Listen normally on 2 lo 127.0.0.1:123 Dec 24 04:19:44 hifi ntpd[683]: Listening on routing socket on fd #19 for interface updates Dec 24 04:19:44 hifi ntpd[683]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 24 04:19:44 hifi ntpd[683]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 24 04:19:45 hifi nmbd[664]: [2022/12/24 04:19:45.650925, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 24 04:19:45 hifi nmbd[664]: started asyncdns process 686 Dec 24 04:19:45 hifi nmbd[664]: [2022/12/24 04:19:45.652757, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 24 04:19:45 hifi nmbd[664]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 24 04:19:45 hifi nmbd[664]: [2022/12/24 04:19:45.652893, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 24 04:19:45 hifi nmbd[664]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 24 04:19:45 hifi ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 24 04:19:45 hifi wireless.js[412]: Cleaning previous... Dec 24 04:19:46 hifi sudo[694]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 24 04:19:46 hifi sudo[694]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:46 hifi sudo[694]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:46 hifi sudo[696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 24 04:19:46 hifi sudo[696]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:46 hifi systemd[1]: systemd-rfkill.service: Succeeded. Dec 24 04:19:46 hifi mpd[681]: Dec 24 04:19 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 24 04:19:46 hifi mpd[681]: Dec 24 04:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 24 04:19:46 hifi mpd[681]: Dec 24 04:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 24 04:19:46 hifi sudo[696]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:46 hifi systemd[1]: Started Music Player Daemon. Dec 24 04:19:46 hifi wireless.js[412]: Stopped aP Dec 24 04:19:46 hifi sudo[705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 24 04:19:46 hifi sudo[705]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:46 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 24 04:19:46 hifi sudo[705]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:46 hifi sudo[707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 24 04:19:46 hifi sudo[707]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:46 hifi ntpd[683]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 24 04:19:46 hifi sudo[707]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:46 hifi sudo[714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 24 04:19:46 hifi sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:46 hifi sudo[714]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:46 hifi sudo[716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 24 04:19:46 hifi sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:47 hifi ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 24 04:19:48 hifi ntpd[683]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 24 04:19:49 hifi volumio-remote-updater[432]: [2022-12-24 04:19:49] [info] asio async_connect error: system:111 (Connection refused) Dec 24 04:19:49 hifi volumio-remote-updater[432]: [2022-12-24 04:19:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 24 04:19:49 hifi volumio-remote-updater[432]: [2022-12-24 04:19:49] [error] handle_connect error: Underlying Transport Error Dec 24 04:19:49 hifi kernel: Bluetooth: Core ver 2.22 Dec 24 04:19:49 hifi kernel: NET: Registered protocol family 31 Dec 24 04:19:49 hifi kernel: Bluetooth: HCI device and connection manager initialized Dec 24 04:19:49 hifi kernel: Bluetooth: HCI socket layer initialized Dec 24 04:19:49 hifi kernel: Bluetooth: L2CAP socket layer initialized Dec 24 04:19:49 hifi kernel: Bluetooth: SCO socket layer initialized Dec 24 04:19:49 hifi btuart[413]: bcm43xx_init Dec 24 04:19:49 hifi btuart[413]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Dec 24 04:19:49 hifi btuart[413]: Set Controller UART speed to 3000000 bit/s Dec 24 04:19:49 hifi btuart[413]: Device setup complete Dec 24 04:19:49 hifi kernel: Bluetooth: HCI UART driver ver 2.3 Dec 24 04:19:49 hifi kernel: Bluetooth: HCI UART protocol H4 registered Dec 24 04:19:49 hifi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 24 04:19:49 hifi kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 24 04:19:49 hifi systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 24 04:19:49 hifi systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 24 04:19:49 hifi systemd[1]: Started Load/Save RF Kill Switch Status. Dec 24 04:19:49 hifi systemd[1]: Created slice system-bthelper.slice. Dec 24 04:19:49 hifi systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 24 04:19:49 hifi bthelper[730]: Raspberry Pi BDADDR already set Dec 24 04:19:49 hifi systemd[1]: Started Raspberry Pi bluetooth helper. Dec 24 04:19:49 hifi systemd[1]: Starting Bluetooth service... Dec 24 04:19:49 hifi sudo[716]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:49 hifi wireless.js[412]: Start wireless flow Dec 24 04:19:49 hifi wireless.js[412]: Stopped hotspot (if there).. Dec 24 04:19:49 hifi sudo[742]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 24 04:19:49 hifi sudo[742]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:49 hifi sudo[742]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:49 hifi bluetoothd[740]: Bluetooth daemon 5.50 Dec 24 04:19:49 hifi bluetoothd[740]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 24 04:19:49 hifi systemd[1]: Started Bluetooth service. Dec 24 04:19:49 hifi systemd[1]: Reached target Bluetooth. Dec 24 04:19:49 hifi bluetoothd[740]: Starting SDP server Dec 24 04:19:49 hifi bluetoothd[740]: Excluding (cli) sap Dec 24 04:19:49 hifi sudo[744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 24 04:19:49 hifi sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:49 hifi bluetoothd[740]: Bluetooth management interface 1.18 initialized Dec 24 04:19:49 hifi dbus-daemon[416]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=740 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 24 04:19:49 hifi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 24 04:19:49 hifi kernel: Bluetooth: BNEP filters: protocol multicast Dec 24 04:19:49 hifi kernel: Bluetooth: BNEP socket layer initialized Dec 24 04:19:49 hifi systemd[1]: Starting Hostname Service... Dec 24 04:19:49 hifi bluetoothd[740]: Failed to set privacy: Rejected (0x0b) Dec 24 04:19:49 hifi sudo[744]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:49 hifi wireless.js[412]: DHCP IP Dec 24 04:19:49 hifi wireless.js[412]: Start ap Dec 24 04:19:49 hifi wpa_supplicant[748]: Successfully initialized wpa_supplicant Dec 24 04:19:49 hifi sudo[749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 24 04:19:49 hifi sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:49 hifi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 24 04:19:49 hifi dhcpcd[750]: sending commands to master dhcpcd process Dec 24 04:19:49 hifi dhcpcd[510]: control command: /sbin/dhcpcd Dec 24 04:19:49 hifi sudo[749]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:50 hifi dhcpcd[510]: lo: carrier acquired Dec 24 04:19:50 hifi dhcpcd[510]: DUID 00:01:00:01:2b:1f:b5:d0:e4:5f:01:59:d7:e7 Dec 24 04:19:50 hifi dhcpcd[510]: lo: IAID 6c:6f:00:00 Dec 24 04:19:50 hifi dhcpcd[510]: lo: ipv6_start: Operation not supported Dec 24 04:19:50 hifi dhcpcd[510]: lo: using static address 127.0.0.1/8 Dec 24 04:19:50 hifi dhcpcd[510]: lo: adding route to 127.0.0.0/8 Dec 24 04:19:50 hifi dbus-daemon[416]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 24 04:19:50 hifi systemd[1]: Started Hostname Service. Dec 24 04:19:50 hifi dhcpcd[510]: arp_announce: Invalid argument Dec 24 04:19:50 hifi wireless.js[412]: trying... Dec 24 04:19:50 hifi sudo[780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:50 hifi sudo[780]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:51 hifi sudo[780]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:51 hifi wireless.js[412]: trying... Dec 24 04:19:51 hifi sudo[783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:51 hifi sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:51 hifi sudo[783]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:52 hifi wpa_supplicant[766]: wlan0: Trying to associate with SSID 'DODO-3D4B' Dec 24 04:19:52 hifi wireless.js[412]: trying... Dec 24 04:19:52 hifi sudo[786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:52 hifi sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:53 hifi sudo[786]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:53 hifi wireless.js[412]: trying... Dec 24 04:19:53 hifi sudo[789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:53 hifi sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:54 hifi sudo[789]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:54 hifi systemd[1]: systemd-rfkill.service: Succeeded. Dec 24 04:19:54 hifi bthelper[730]: Changing power off succeeded Dec 24 04:19:54 hifi bthelper[730]: [CHG] Controller E4:5F:01:59:D7:E8 Class: 0x0000041c Dec 24 04:19:54 hifi bthelper[730]: Changing power on succeeded Dec 24 04:19:54 hifi wireless.js[412]: trying... Dec 24 04:19:54 hifi sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:54 hifi sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:55 hifi sudo[799]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:55 hifi wpa_supplicant[766]: wlan0: Associated with 80:7d:14:45:3d:54 Dec 24 04:19:55 hifi wpa_supplicant[766]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:7d:14:45:3d:54 completed [id=0 id_str=] Dec 24 04:19:55 hifi dhcpcd[510]: wlan0: carrier acquired Dec 24 04:19:55 hifi wpa_supplicant[766]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 24 04:19:55 hifi dhcpcd[510]: wlan0: connected to Access Point `DODO-3D4B' Dec 24 04:19:55 hifi dhcpcd[510]: wlan0: IAID 01:59:d7:e7 Dec 24 04:19:55 hifi dhcpcd[510]: wlan0: adding address fe80::e65f:1ff:fe59:d7e7 Dec 24 04:19:55 hifi dhcpcd[510]: ipv6_addaddr1: Permission denied Dec 24 04:19:55 hifi wireless.js[412]: trying... Dec 24 04:19:55 hifi sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:56 hifi sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:56 hifi sudo[803]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:56 hifi dhcpcd[510]: wlan0: soliciting an IPv6 router Dec 24 04:19:56 hifi dhcpcd[510]: wlan0: rebinding lease of 192.168.1.10 Dec 24 04:19:56 hifi volumio-remote-updater[432]: [2022-12-24 04:19:56] [info] asio async_connect error: system:111 (Connection refused) Dec 24 04:19:56 hifi volumio-remote-updater[432]: [2022-12-24 04:19:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 24 04:19:56 hifi volumio-remote-updater[432]: [2022-12-24 04:19:56] [error] handle_connect error: Underlying Transport Error Dec 24 04:19:56 hifi dhcpcd[510]: wlan0: probing address 192.168.1.10/24 Dec 24 04:19:56 hifi wireless.js[412]: trying... Dec 24 04:19:57 hifi sudo[806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:57 hifi sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:57 hifi sudo[806]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:57 hifi wireless.js[412]: trying... Dec 24 04:19:58 hifi sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:58 hifi sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:58 hifi sudo[809]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:58 hifi kernel: cam-dummy-reg: disabling Dec 24 04:19:58 hifi kernel: cam1-reg: disabling Dec 24 04:19:58 hifi wireless.js[412]: trying... Dec 24 04:19:59 hifi sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:19:59 hifi sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:19:59 hifi sudo[812]: pam_unix(sudo:session): session closed for user root Dec 24 04:19:59 hifi wireless.js[412]: trying... Dec 24 04:20:00 hifi sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:20:00 hifi sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:00 hifi sudo[815]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:00 hifi wireless.js[412]: trying... Dec 24 04:20:01 hifi sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:20:01 hifi sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:01 hifi sudo[818]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:01 hifi dhcpcd[510]: wlan0: leased 192.168.1.10 for 86400 seconds Dec 24 04:20:01 hifi avahi-daemon[419]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.10. Dec 24 04:20:01 hifi avahi-daemon[419]: New relevant interface wlan0.IPv4 for mDNS. Dec 24 04:20:01 hifi dhcpcd[510]: wlan0: adding route to 192.168.1.0/24 Dec 24 04:20:01 hifi avahi-daemon[419]: Registering new address record for 192.168.1.10 on wlan0.IPv4. Dec 24 04:20:01 hifi dhcpcd[510]: wlan0: adding default route via 192.168.1.1 Dec 24 04:20:01 hifi wireless.js[412]: trying... Dec 24 04:20:02 hifi sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 24 04:20:02 hifi sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:02 hifi sudo[842]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:02 hifi wireless.js[412]: Connected to: ----DODO-3D4B Dec 24 04:20:02 hifi wireless.js[412]: ---- Dec 24 04:20:02 hifi sudo[845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 04:20:02 hifi sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:02 hifi sudo[845]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:02 hifi wireless.js[412]: ... joined AP, wlan0 IPv4 is 192.168.1.10, ipV6 is undefined Dec 24 04:20:02 hifi wireless.js[412]: It's done! AP Dec 24 04:20:02 hifi systemd[1]: Started Wireless Services. Dec 24 04:20:02 hifi systemd[1]: Started Volumio Backend Module. Dec 24 04:20:02 hifi systemd[1]: Started Volumio Streaming Daemon. Dec 24 04:20:02 hifi volumio-streaming-daemon[850]: ############################ Dec 24 04:20:02 hifi volumio-streaming-daemon[850]: # Volumio Streaming Daemon # Dec 24 04:20:02 hifi volumio-streaming-daemon[850]: # Running on port 7777 # Dec 24 04:20:02 hifi volumio-streaming-daemon[850]: ############################ Dec 24 04:20:02 hifi volumio-streaming-daemon[850]: Environment: production Dec 24 04:20:02 hifi ntpd[683]: Listen normally on 3 wlan0 192.168.1.10:123 Dec 24 04:20:02 hifi ntpd[683]: new interface(s) found: waking up resolver Dec 24 04:20:03 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:03 hifi volumio[849]: info: ----- Volumio3 ---- Dec 24 04:20:03 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:03 hifi volumio[849]: info: ----- System startup ---- Dec 24 04:20:03 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:04 hifi volumio[849]: info: MYVOLUMIO Environment detected Dec 24 04:20:04 hifi volumio[849]: info: Plugin folders cleanup Dec 24 04:20:04 hifi volumio[849]: info: Scanning into folder /volumio/app/plugins/ Dec 24 04:20:04 hifi volumio[849]: info: Scanning category audio_interface Dec 24 04:20:04 hifi volumio[849]: info: Scanning category miscellanea Dec 24 04:20:04 hifi volumio[849]: info: Scanning category music_service Dec 24 04:20:04 hifi volumio[849]: info: Scanning category plugins.json Dec 24 04:20:04 hifi volumio[849]: info: Scanning category system_controller Dec 24 04:20:04 hifi volumio[849]: info: Scanning category user_interface Dec 24 04:20:04 hifi volumio[849]: info: Scanning into folder /data/plugins/ Dec 24 04:20:04 hifi volumio[849]: info: Plugin folders cleanup completed Dec 24 04:20:04 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:04 hifi volumio[849]: info: ----- Core plugins startup ---- Dec 24 04:20:04 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:04 hifi volumio[849]: info: Loading plugins from folder /volumio/app/plugins/ Dec 24 04:20:04 hifi volumio[849]: info: Adding plugin upnp to MyMusic Plugins Dec 24 04:20:04 hifi volumio[849]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 24 04:20:04 hifi volumio[849]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 24 04:20:04 hifi volumio[849]: info: Loading plugins from folder /data/plugins/ Dec 24 04:20:04 hifi volumio[849]: info: Loading plugin "system"... Dec 24 04:20:04 hifi volumio[849]: info: Loading plugin "appearance"... Dec 24 04:20:05 hifi nmbd[664]: [2022/12/24 04:20:05.659686, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 24 04:20:05 hifi systemd[1]: Started Samba NMB Daemon. Dec 24 04:20:05 hifi nmbd[664]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 24 04:20:05 hifi systemd[1]: Starting Samba Winbind Daemon... Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "network"... Dec 24 04:20:05 hifi volumio[849]: info: Refreshing Cached IP Addresses Dec 24 04:20:05 hifi sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 04:20:05 hifi sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:05 hifi sudo[875]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "services"... Dec 24 04:20:05 hifi sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 04:20:05 hifi sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "alsa_controller"... Dec 24 04:20:05 hifi sudo[877]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:05 hifi sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 24 04:20:05 hifi sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:05 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "wizard"... Dec 24 04:20:05 hifi winbindd[872]: [2022/12/24 04:20:05.873994, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 24 04:20:05 hifi winbindd[872]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "volumio_command_line_client"... Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "upnp"... Dec 24 04:20:05 hifi systemd[1]: Started Samba Winbind Daemon. Dec 24 04:20:05 hifi winbindd[872]: [2022/12/24 04:20:05.891829, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 24 04:20:05 hifi winbindd[872]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 24 04:20:05 hifi volumio[849]: info: [1671855605892] Starting Upmpd Daemon Dec 24 04:20:05 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "my_music"... Dec 24 04:20:05 hifi systemd[1]: Starting Samba SMB Daemon... Dec 24 04:20:05 hifi volumio[849]: info: Loading plugin "mpd"... Dec 24 04:20:06 hifi winbindd[872]: [2022/12/24 04:20:06.262226, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 24 04:20:06 hifi winbindd[872]: res_names->count = 3, expected 5 Dec 24 04:20:06 hifi smbd[890]: [2022/12/24 04:20:06.283640, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 24 04:20:06 hifi smbd[890]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 24 04:20:06 hifi systemd[1]: Started Samba SMB Daemon. Dec 24 04:20:06 hifi systemd[1]: Reached target Multi-User System. Dec 24 04:20:06 hifi systemd[1]: Reached target Graphical Interface. Dec 24 04:20:06 hifi systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 24 04:20:06 hifi volumio[849]: info: Creating MPD Configuration file Dec 24 04:20:06 hifi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 24 04:20:06 hifi systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 24 04:20:06 hifi systemd[1]: Startup finished in 10.453s (kernel) + 30.934s (userspace) = 41.387s. Dec 24 04:20:06 hifi volumio[849]: info: Loading plugin "upnp_browser"... Dec 24 04:20:06 hifi sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 04:20:06 hifi sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:06 hifi sudo[900]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:06 hifi sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 04:20:06 hifi sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:06 hifi systemd[1]: Stopping Music Player Daemon... Dec 24 04:20:06 hifi systemd[1]: mpd.service: Succeeded. Dec 24 04:20:06 hifi systemd[1]: Stopped Music Player Daemon. Dec 24 04:20:06 hifi systemd[1]: Starting Music Player Daemon... Dec 24 04:20:06 hifi volumio-remote-updater[432]: [2022-12-24 04:20:06] [connect] Successful connection Dec 24 04:20:06 hifi volumio[849]: info: Loading plugin "networkfs"... Dec 24 04:20:06 hifi volumio[849]: info: Starting Udev Watcher for removable devices Dec 24 04:20:07 hifi mpd[907]: Dec 24 04:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 24 04:20:07 hifi mpd[907]: Dec 24 04:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 24 04:20:07 hifi mpd[907]: Dec 24 04:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 24 04:20:07 hifi systemd[1]: Started Music Player Daemon. Dec 24 04:20:07 hifi sudo[902]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:07 hifi volumio[849]: info: Ignoring mount for partition: boot Dec 24 04:20:07 hifi volumio[849]: info: Ignoring mount for partition: volumio Dec 24 04:20:07 hifi volumio[849]: info: Ignoring mount for partition: volumio_data Dec 24 04:20:07 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "alarm-clock"... Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "airplay_emulation"... Dec 24 04:20:07 hifi volumio[849]: info: Starting Shairport Sync Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "last_100"... Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "webradio"... Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "i2s_dacs"... Dec 24 04:20:07 hifi volumio[849]: info: I2S DAC not set, start Auto-detection Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "volumiodiscovery"... Dec 24 04:20:07 hifi volumio[849]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 24 04:20:07 hifi volumio[849]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 04:20:07 hifi volumio[849]: *** WARNING *** For more information see Dec 24 04:20:07 hifi node[849]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 24 04:20:07 hifi volumio[849]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 24 04:20:07 hifi volumio[849]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 04:20:07 hifi volumio[849]: *** WARNING *** For more information see Dec 24 04:20:07 hifi node[849]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 04:20:07 hifi node[849]: *** WARNING *** For more information see Dec 24 04:20:07 hifi node[849]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 24 04:20:07 hifi node[849]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 04:20:07 hifi node[849]: *** WARNING *** For more information see Dec 24 04:20:07 hifi volumio[849]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 24 04:20:07 hifi volumio[849]: info: Discovery: Started advertising with name: HiFi Dec 24 04:20:07 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "outputs"... Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "albumart"... Dec 24 04:20:07 hifi volumio[849]: info: Plugin example_plugin is not enabled Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "inputs"... Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "updater_comm"... Dec 24 04:20:07 hifi volumio[849]: info: Plugin mpdemulation is not enabled Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "rest_api"... Dec 24 04:20:07 hifi volumio[849]: info: Loading plugin "websocket"... Dec 24 04:20:07 hifi volumio[849]: info: Loading i18n strings for locale en Dec 24 04:20:07 hifi volumio[849]: Updating browse sources language Dec 24 04:20:07 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 04:20:07 hifi volumio[849]: Forking 3 albumart workers Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::initPlayerControls Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 04:20:08 hifi volumio[849]: Express server listening on port 3000 Dec 24 04:20:08 hifi volumio[849]: [Metrics] WebUI: 4s 833.29ms Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::resetVolumioState Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::getcurrentVolume Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: Cannot read play queue from file Dec 24 04:20:08 hifi volumio[849]: info: MPD Permissions set Dec 24 04:20:08 hifi volumio[849]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.2 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 1 Dec 24 04:20:08 hifi volumio-remote-updater[432]: [2022-12-24 04:20:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1671855606 101 Dec 24 04:20:08 hifi volumio[849]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Dec 24 04:20:08 hifi volumio[849]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.2 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 Dec 24 04:20:08 hifi volumio[849]: info: MPD running with PID907 Dec 24 04:20:08 hifi volumio[849]: ,establishing connection Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: Starting albumart workers Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: error: updateQueue error: null Dec 24 04:20:08 hifi volumio[849]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::setRepeat null single undefined Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:08 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::setRandom null Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:08 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:08 hifi volumio[849]: info: Setting Device type: Raspberry PI Dec 24 04:20:08 hifi volumio[849]: info: VolumeController:: Volume=62 Mute =false Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:08 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:08 hifi volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 24 04:20:08 hifi volumio[849]: info: CorePlayQueue::getTrackBlock Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 04:20:08 hifi volumio[849]: error: updateQueue error: null Dec 24 04:20:08 hifi volumio[849]: info: Completed loading Core Plugins Dec 24 04:20:08 hifi volumio[849]: info: Preparing to generate the ALSA configuration file Dec 24 04:20:08 hifi volumio[849]: Starting albumart workers Dec 24 04:20:08 hifi volumio[849]: Starting albumart workers Dec 24 04:20:08 hifi volumio[849]: info: Asound.conf file written Dec 24 04:20:08 hifi sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 24 04:20:08 hifi sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:08 hifi sudo[960]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:08 hifi volumio[849]: info: Output device has changed, restarting MPD Dec 24 04:20:08 hifi volumio[849]: info: Output device has changed, restarting Shairport Sync Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:08 hifi sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 04:20:08 hifi sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 04:20:08 hifi sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:08 hifi sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:08 hifi sudo[966]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:08 hifi volumio[849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 04:20:08 hifi volumio[849]: info: ___________ START PLUGINS ___________ Dec 24 04:20:08 hifi systemd[1]: Stopping Music Player Daemon... Dec 24 04:20:08 hifi systemd[1]: mpd.service: Succeeded. Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 24 04:20:08 hifi systemd[1]: Stopped Music Player Daemon. Dec 24 04:20:08 hifi volumio[849]: info: [1671855608822] CoreMusicLibrary::Adding element Media Servers Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 04:20:08 hifi volumio[849]: Cannot find translation for sourceMedia Servers Dec 24 04:20:08 hifi systemd[1]: Starting Music Player Daemon... Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:08 hifi volumio[849]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 24 04:20:08 hifi volumio[849]: info: [1671855608988] CoreMusicLibrary::Adding element Last_100 Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 04:20:08 hifi volumio[849]: Cannot find translation for sourceMedia Servers Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 24 04:20:08 hifi volumio[849]: info: [1671855608992] CoreMusicLibrary::Adding element Webradio Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 04:20:08 hifi volumio[849]: Cannot find translation for sourceMedia Servers Dec 24 04:20:08 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 04:20:09 hifi volumio[849]: info: Volumio Calling Home Dec 24 04:20:09 hifi volumio[849]: info: Completed starting Core Plugins Dec 24 04:20:09 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:09 hifi volumio[849]: info: ----- MyVolumio plugins startup ---- Dec 24 04:20:09 hifi volumio[849]: info: ------------------------------------------- Dec 24 04:20:09 hifi volumio[849]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 24 04:20:09 hifi volumio[849]: info: Discovery: adding 9ad6e03a-fe43-4a4f-8420-aeda46c43d88 Dec 24 04:20:09 hifi volumio[849]: info: Discovery: Found device HiFi Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::volumioGetState Dec 24 04:20:09 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:09 hifi volumio[849]: info: MPD Permissions set Dec 24 04:20:09 hifi volumio[849]: info: VolumeController:: Volume=62 Mute =false Dec 24 04:20:09 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:09 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:09 hifi volumio[849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:09 hifi volumio[849]: info: Starting Shairport Sync Dec 24 04:20:09 hifi volumio[849]: info: Starting Shairport Sync Dec 24 04:20:09 hifi volumio[849]: info: Starting Shairport Sync Dec 24 04:20:09 hifi sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 04:20:09 hifi sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:09 hifi sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 04:20:09 hifi sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:09 hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 24 04:20:09 hifi systemd[1]: shairport-sync.service: Succeeded. Dec 24 04:20:09 hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 24 04:20:09 hifi sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 04:20:09 hifi sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:09 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 24 04:20:09 hifi sudo[1014]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:09 hifi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 24 04:20:09 hifi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 24 04:20:09 hifi volumio[849]: info: Shairport-Sync Started Dec 24 04:20:09 hifi systemd[1]: shairport-sync.service: Succeeded. Dec 24 04:20:09 hifi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 24 04:20:09 hifi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 24 04:20:09 hifi sudo[1020]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:09 hifi sudo[1016]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:09 hifi volumio[849]: Error adding Membership: Error: addMembership EINVAL Dec 24 04:20:09 hifi volumio[849]: info: Shairport-Sync Started Dec 24 04:20:09 hifi volumio[849]: info: Shairport-Sync Started Dec 24 04:20:09 hifi volumio[849]: info: CoreCommandRouter::volumioGetState Dec 24 04:20:09 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:09 hifi sudo[880]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:09 hifi mpd[993]: Dec 24 04:20 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 24 04:20:09 hifi mpd[993]: Dec 24 04:20 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 24 04:20:09 hifi mpd[993]: Dec 24 04:20 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 24 04:20:09 hifi systemd[1]: Started Music Player Daemon. Dec 24 04:20:09 hifi sudo[968]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:09 hifi volumio[849]: error: updateQueue error: null Dec 24 04:20:09 hifi volumio[849]: info: Volumio called home Dec 24 04:20:11 hifi systemd[1]: systemd-fsckd.service: Succeeded. Dec 24 04:20:12 hifi volumio[849]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.2 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 5 Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::volumioGetState Dec 24 04:20:12 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::volumioGetState Dec 24 04:20:12 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 04:20:12 hifi volumio[849]: info: Received Get System Info Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 04:20:12 hifi volumio[849]: info: Discovery: Getting this device information Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::volumioGetState Dec 24 04:20:12 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 04:20:12 hifi volumio[849]: info: CoreCommandRouter::volumioGetState Dec 24 04:20:12 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:12 hifi volumio[849]: info: Listing playlists Dec 24 04:20:15 hifi sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 04:20:15 hifi sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:15 hifi sudo[1029]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:15 hifi sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 04:20:15 hifi sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:15 hifi sudo[1031]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:15 hifi sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 24 04:20:15 hifi sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 04:20:15 hifi systemd[1]: Started UPnP Renderer front-end to MPD. Dec 24 04:20:15 hifi sudo[1035]: pam_unix(sudo:session): session closed for user root Dec 24 04:20:15 hifi volumio[849]: info: Upmpdcli Daemon Started Dec 24 04:20:16 hifi volumio[1037]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 24 04:20:20 hifi systemd[1]: systemd-hostnamed.service: Succeeded. Dec 24 04:20:20 hifi volumio[1037]: ............................................++++ Dec 24 04:20:28 hifi volumio[849]: info: CoreCommandRouter::volumioPlay Dec 24 04:20:28 hifi volumio[849]: info: CoreStateMachine::play index undefined Dec 24 04:20:28 hifi volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 04:20:28 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:29 hifi volumio[849]: error: MyVolumio Plugin failed to start in a timely fashion Dec 24 04:20:29 hifi volumio[849]: info: BOOT COMPLETED Dec 24 04:20:29 hifi volumio[849]: [Metrics] CommandRouter: 25s 255.10ms Dec 24 04:20:29 hifi volumio[849]: info: CoreCommandRouter::volumiosetStartupVolume Dec 24 04:20:29 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 04:20:29 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:29 hifi volumio[849]: info: CoreCommandRouter::Close All Modals sent Dec 24 04:20:29 hifi volumio[849]: info: CoreCommandRouter::Close All Modals sent Dec 24 04:20:30 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 24 04:20:30 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 04:20:30 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 24 04:20:39 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 04:20:39 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 04:20:41 hifi volumio[1037]: .............................................................................................................................................................................................................................................++++ Dec 24 04:20:41 hifi volumio[1037]: e is 65537 (0x010001) Dec 24 04:20:41 hifi volumio[1037]: writing RSA key Dec 24 04:20:49 hifi volumio[849]: info: Airplay started streaming, receiving metadatas Dec 24 04:20:49 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:49 hifi volumio[849]: info: CoreCommandRouter::volumioStop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::stop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 04:20:49 hifi volumio[849]: info: Airplay started streaming, receiving metadatas Dec 24 04:20:49 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:49 hifi volumio[849]: info: CoreCommandRouter::volumioStop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::stop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 04:20:49 hifi volumio[849]: info: Airplay started streaming, receiving metadatas Dec 24 04:20:49 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:49 hifi volumio[849]: info: CoreCommandRouter::volumioStop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::stop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 04:20:49 hifi shairport-sync[1023]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 24 04:20:49 hifi shairport-sync[1023]: Dload Upload Total Spent Left Speed Dec 24 04:20:49 hifi volumio[849]: info: Dec 24 04:20:49 hifi volumio[849]: ---------------------------- Client requests Start Airplay PlaybackRoutine Dec 24 04:20:49 hifi volumio[849]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Dec 24 04:20:49 hifi volumio[849]: info: Airplay playback start Dec 24 04:20:49 hifi volumio[849]: info: CorePlayQueue::getTrack 0 Dec 24 04:20:49 hifi volumio[849]: info: CoreCommandRouter::volumioStop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::stop Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 04:20:49 hifi volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 04:20:49 hifi shairport-sync[1023]: [158B blob data] Dec 24 04:20:49 hifi ntpd[683]: Soliciting pool server 159.196.44.158 Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:50 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:50 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:50 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:50 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:50 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:52 hifi ntpd[683]: Soliciting pool server 180.150.8.191 Dec 24 04:20:52 hifi ntpd[683]: Soliciting pool server 129.250.35.250 Dec 24 04:20:53 hifi ntpd[683]: Soliciting pool server 162.159.200.1 Dec 24 04:20:54 hifi ntpd[683]: Soliciting pool server 103.115.90.41 Dec 24 04:20:56 hifi ntpd[683]: Soliciting pool server 129.250.35.251 Dec 24 04:20:56 hifi ntpd[683]: Soliciting pool server 119.18.34.76 Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:57 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:57 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:57 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:57 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:57 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::servicePushState Dec 24 04:20:57 hifi volumio[849]: info: CoreStateMachine::pushState Dec 24 04:20:57 hifi volumio[849]: info: CoreCommandRouter::volumioPushState Dec 24 04:20:58 hifi volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 04:20:58 hifi volumio[849]: Error: certificate is not yet valid Dec 24 04:20:58 hifi volumio[849]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Dec 24 04:20:58 hifi volumio[849]: at TLSSocket.emit (events.js:315:20) Dec 24 04:20:58 hifi volumio[849]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Dec 24 04:20:58 hifi volumio[849]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Dec 24 04:20:58 hifi volumio[849]: code: 'CERT_NOT_YET_VALID' Dec 24 04:20:58 hifi volumio[849]: } Dec 24 04:20:58 hifi volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 04:20:58 hifi sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-12-24 04:19 Dec 24 04:20:58 hifi sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"