-- Logs begin at Tue 2020-12-22 13:39:52 UTC, end at Tue 2020-12-22 13:41:02 UTC. -- Dec 22 13:40:00 volumio3 rpc.statd[566]: Version 1.2.8 starting Dec 22 13:40:00 volumio3 sm-notify[567]: Version 1.2.8 starting Dec 22 13:40:00 volumio3 kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Dec 22 13:40:00 volumio3 nfs-common[559]: Starting NFS common utilities: statd idmapd. Dec 22 13:40:00 volumio3 systemd[1]: Started LSB: NFS support files common to client and server. Dec 22 13:40:00 volumio3 systemd[1]: Starting System Initialization. Dec 22 13:40:00 volumio3 systemd[1]: Reached target System Initialization. Dec 22 13:40:00 volumio3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Dec 22 13:40:00 volumio3 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 22 13:40:00 volumio3 systemd[1]: Starting mpd.socket. Dec 22 13:40:00 volumio3 systemd[1]: Listening on mpd.socket. Dec 22 13:40:00 volumio3 systemd[1]: Starting D-Bus System Message Bus Socket. Dec 22 13:40:00 volumio3 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 22 13:40:00 volumio3 systemd[1]: Starting Sockets. Dec 22 13:40:00 volumio3 systemd[1]: Reached target Sockets. Dec 22 13:40:00 volumio3 systemd[1]: Starting Daily Cleanup of Temporary Directories. Dec 22 13:40:00 volumio3 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 22 13:40:00 volumio3 systemd[1]: Starting Timers. Dec 22 13:40:00 volumio3 systemd[1]: Reached target Timers. Dec 22 13:40:00 volumio3 systemd[1]: Started Manage Sound Card State (restore and store). Dec 22 13:40:00 volumio3 systemd[1]: Starting Restore Sound Card State... Dec 22 13:40:00 volumio3 systemd[1]: Starting Basic System. Dec 22 13:40:00 volumio3 systemd[1]: Reached target Basic System. Dec 22 13:40:00 volumio3 systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Dec 22 13:40:00 volumio3 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 22 13:40:00 volumio3 systemd[1]: Starting cd.service... Dec 22 13:40:00 volumio3 systemd[1]: Started cd.service. Dec 22 13:40:00 volumio3 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 22 13:40:00 volumio3 systemd[1]: Starting Volumio Iptables Module... Dec 22 13:40:00 volumio3 systemd[1]: Started Volumio Iptables Module. Dec 22 13:40:00 volumio3 systemd[1]: Starting mount disks automatically with standby... Dec 22 13:40:00 volumio3 systemd[1]: Started mount disks automatically with standby. Dec 22 13:40:00 volumio3 alsactl[579]: No state is present for card ALSA Dec 22 13:40:00 volumio3 alsactl[579]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" "" Dec 22 13:40:00 volumio3 alsactl[579]: Hardware is initialized using a generic method Dec 22 13:40:00 volumio3 alsactl[579]: No state is present for card ALSA Dec 22 13:40:00 volumio3 alsactl[579]: No state is present for card Headphones Dec 22 13:40:00 volumio3 alsactl[579]: Found hardware: "bcm2835_headpho" "Broadcom Mixer" "" "" "" Dec 22 13:40:00 volumio3 alsactl[579]: Hardware is initialized using a generic method Dec 22 13:40:00 volumio3 systemd[1]: Starting UPnP Renderer front-end to MPD... Dec 22 13:40:00 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 22 13:40:00 volumio3 systemd[1]: Starting volumio-remote-updater.service... Dec 22 13:40:00 volumio3 systemd[1]: Started volumio-remote-updater.service. Dec 22 13:40:00 volumio3 alsactl[579]: No state is present for card Headphones Dec 22 13:40:00 volumio3 alsactl[579]: No state is present for card sndrpihifiberry Dec 22 13:40:00 volumio3 alsactl[579]: Found hardware: "HifiberryDacp" "" "" "" "" Dec 22 13:40:00 volumio3 alsactl[579]: Hardware is initialized using a generic method Dec 22 13:40:00 volumio3 alsactl[579]: No state is present for card sndrpihifiberry Dec 22 13:40:00 volumio3 systemd[1]: Starting Volumio Log Rotation Service... Dec 22 13:40:00 volumio3 systemd[1]: Started Volumio Log Rotation Service. Dec 22 13:40:00 volumio3 systemd[1]: Starting Volumio SSH enabler... Dec 22 13:40:00 volumio3 systemd[1]: Started Volumio SSH enabler. Dec 22 13:40:00 volumio3 systemd[1]: Starting Wireless Services... Dec 22 13:40:00 volumio3 systemd[1]: Starting /etc/rc.local Compatibility... Dec 22 13:40:00 volumio3 systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Dec 22 13:40:00 volumio3 systemd[1]: Starting Login Service... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: start Winbind daemon... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: triggerhappy hotkey daemon... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: start Samba daemons for the AD DC... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: Start NTP daemon... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: Brings up/down network automatically... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Dec 22 13:40:00 volumio3 systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Dec 22 13:40:00 volumio3 volumio[581]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 22 13:40:00 volumio3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 22 13:40:00 volumio3 systemd[1]: Starting Music Player Daemon... Dec 22 13:40:00 volumio3 systemd[1]: Starting D-Bus System Message Bus... Dec 22 13:40:00 volumio3 systemd[1]: Started D-Bus System Message Bus. Dec 22 13:40:00 volumio3 dhcpcd[610]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Dec 22 13:40:00 volumio3 dhcpcd[610]: /var/run/dhcpcd-eth0.pid ... failed! Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Unable to parse trigger line: Dec 22 13:40:00 volumio3 triggerhappy[597]: Error opening '/dev/input/event*': No such file or directory Dec 22 13:40:00 volumio3 kernel: uart-pl011 3f201000.serial: no DMA platform data Dec 22 13:40:01 volumio3 btuart[582]: Patch not found for BCM4345C0, continue anyway Dec 22 13:40:01 volumio3 ifplugd(eth0)[692]: ifplugd 0.28 initializing. Dec 22 13:40:01 volumio3 ifplugd(eth0)[692]: Using interface eth0/B8:27:EB:44:35:BB with driver (version: ) Dec 22 13:40:01 volumio3 ifplugd(eth0)[692]: Using detection mode: SIOCETHTOOL Dec 22 13:40:01 volumio3 ifplugd(eth0)[692]: Initialization complete, link beat not detected. Dec 22 13:40:01 volumio3 ifplugd[604]: Network Interface Plugging Daemon...start eth0...done. Dec 22 13:40:01 volumio3 loadcpufreq[602]: Loading cpufreq kernel modules...done (none). Dec 22 13:40:01 volumio3 avahi-daemon[632]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Dec 22 13:40:01 volumio3 avahi-daemon[632]: Successfully dropped root privileges. Dec 22 13:40:01 volumio3 avahi-daemon[632]: avahi-daemon 0.6.31 starting up. Dec 22 13:40:01 volumio3 kernel: Bluetooth: Core ver 2.22 Dec 22 13:40:01 volumio3 kernel: NET: Registered protocol family 31 Dec 22 13:40:01 volumio3 kernel: Bluetooth: HCI device and connection manager initialized Dec 22 13:40:01 volumio3 kernel: Bluetooth: HCI socket layer initialized Dec 22 13:40:01 volumio3 kernel: Bluetooth: L2CAP socket layer initialized Dec 22 13:40:01 volumio3 kernel: Bluetooth: SCO socket layer initialized Dec 22 13:40:01 volumio3 btuart[582]: bcm43xx_init Dec 22 13:40:01 volumio3 btuart[582]: Set BDADDR UART: b8:27:eb:ee:9f:11 Dec 22 13:40:01 volumio3 btuart[582]: Set Controller UART speed to 3000000 bit/s Dec 22 13:40:01 volumio3 btuart[582]: Device setup complete Dec 22 13:40:01 volumio3 kernel: Bluetooth: HCI UART driver ver 2.3 Dec 22 13:40:01 volumio3 kernel: Bluetooth: HCI UART protocol H4 registered Dec 22 13:40:01 volumio3 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 22 13:40:01 volumio3 kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 22 13:40:01 volumio3 systemd-udevd[726]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory Dec 22 13:40:02 volumio3 avahi-daemon[632]: Successfully called chroot(). Dec 22 13:40:02 volumio3 avahi-daemon[632]: Successfully dropped remaining capabilities. Dec 22 13:40:02 volumio3 dbus[638]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 22 13:40:02 volumio3 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 22 13:40:02 volumio3 systemd[1]: Starting Permit User Sessions... Dec 22 13:40:02 volumio3 systemd[1]: Started Restore Sound Card State. Dec 22 13:40:02 volumio3 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 22 13:40:02 volumio3 systemd[1]: Unit cd.service entered failed state. Dec 22 13:40:02 volumio3 avahi-daemon[632]: Loading service file /services/volumio.service. Dec 22 13:40:02 volumio3 systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 22 13:40:02 volumio3 systemd[1]: Started /etc/rc.local Compatibility. Dec 22 13:40:02 volumio3 systemd[1]: Started LSB: triggerhappy hotkey daemon. Dec 22 13:40:02 volumio3 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 22 13:40:02 volumio3 systemd[1]: Started LSB: Brings up/down network automatically. Dec 22 13:40:02 volumio3 systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Dec 22 13:40:02 volumio3 systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Dec 22 13:40:02 volumio3 systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Dec 22 13:40:02 volumio3 systemd[1]: Unit dhcpcd.service entered failed state. Dec 22 13:40:02 volumio3 avahi-daemon[632]: Network interface enumeration completed. Dec 22 13:40:02 volumio3 avahi-daemon[632]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Dec 22 13:40:02 volumio3 avahi-daemon[632]: Server startup complete. Host name is volumio3.local. Local service cookie is 2894232130. Dec 22 13:40:02 volumio3 avahi-daemon[632]: Service "Volumio3" (/services/volumio.service) successfully established. Dec 22 13:40:02 volumio3 ntpd[681]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Dec 22 13:40:02 volumio3 systemd[1]: Started LSB: Start NTP daemon. Dec 22 13:40:02 volumio3 ntp[599]: Starting NTP server: ntpd. Dec 22 13:40:02 volumio3 systemd[1]: Started Permit User Sessions. Dec 22 13:40:02 volumio3 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 22 13:40:02 volumio3 ntpd[730]: proto: precision = 1.094 usec Dec 22 13:40:02 volumio3 ntpd[730]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Dec 22 13:40:02 volumio3 ntpd[730]: Listen and drop on 1 v6wildcard :: UDP 123 Dec 22 13:40:02 volumio3 ntpd[730]: Listen normally on 2 lo 127.0.0.1 UDP 123 Dec 22 13:40:02 volumio3 ntpd[730]: peers refreshed Dec 22 13:40:02 volumio3 ntpd[730]: Listening on routing socket on fd #19 for interface updates Dec 22 13:40:02 volumio3 systemd-logind[595]: New seat seat0. Dec 22 13:40:02 volumio3 systemd[1]: Started Login Service. Dec 22 13:40:02 volumio3 systemd[1]: Stopping cd.service... Dec 22 13:40:02 volumio3 systemd[1]: Starting cd.service... Dec 22 13:40:02 volumio3 systemd[1]: Started cd.service. Dec 22 13:40:02 volumio3 systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Dec 22 13:40:02 volumio3 systemd[1]: Starting Bluetooth service... Dec 22 13:40:02 volumio3 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 22 13:40:02 volumio3 systemd[1]: Starting Hold until boot process finishes up... Dec 22 13:40:02 volumio3 volumio[732]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 22 13:40:02 volumio3 systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 22 13:40:02 volumio3 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 22 13:40:02 volumio3 systemd[1]: Unit cd.service entered failed state. Dec 22 13:40:02 volumio3 systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Dec 22 13:40:03 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 250 (plymouthd). Dec 22 13:40:03 volumio3 systemd[1]: Started Terminate Plymouth Boot Screen. Dec 22 13:40:03 volumio3 systemd[1]: Started Hold until boot process finishes up. Dec 22 13:40:03 volumio3 systemd[1]: Starting Getty on tty1... Dec 22 13:40:03 volumio3 systemd[1]: Started Getty on tty1. Dec 22 13:40:03 volumio3 systemd[1]: Starting Login Prompts. Dec 22 13:40:03 volumio3 systemd[1]: Reached target Login Prompts. Dec 22 13:40:03 volumio3 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 22 13:40:03 volumio3 systemd[1]: Stopping cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Starting cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Started cd.service. Dec 22 13:40:03 volumio3 volumio[752]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 22 13:40:03 volumio3 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 22 13:40:03 volumio3 systemd[1]: Unit cd.service entered failed state. Dec 22 13:40:03 volumio3 systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 22 13:40:03 volumio3 cpufrequtils[736]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 22 13:40:03 volumio3 haveged[580]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Dec 22 13:40:03 volumio3 haveged[580]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Dec 22 13:40:03 volumio3 haveged[580]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9993 Dec 22 13:40:03 volumio3 haveged[580]: haveged: fills: 0, generated: 0 Dec 22 13:40:03 volumio3 dbus[638]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Dec 22 13:40:03 volumio3 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 22 13:40:03 volumio3 systemd[1]: Starting Disk Manager (legacy version)... Dec 22 13:40:03 volumio3 systemd[1]: Stopping cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Starting cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Started cd.service. Dec 22 13:40:03 volumio3 bluetoothd[735]: Bluetooth daemon 5.23 Dec 22 13:40:03 volumio3 volumio[759]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 22 13:40:03 volumio3 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 22 13:40:03 volumio3 systemd[1]: Unit cd.service entered failed state. Dec 22 13:40:03 volumio3 sudo[628]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Dec 22 13:40:03 volumio3 dbus[638]: [system] Successfully activated service 'org.freedesktop.UDisks' Dec 22 13:40:03 volumio3 systemd[1]: Started Disk Manager (legacy version). Dec 22 13:40:03 volumio3 bluetoothd[735]: Starting SDP server Dec 22 13:40:03 volumio3 bluetoothd[735]: Excluding (cli) sap Dec 22 13:40:03 volumio3 systemd[1]: Started Bluetooth service. Dec 22 13:40:03 volumio3 systemd[1]: Starting Bluetooth. Dec 22 13:40:03 volumio3 systemd[1]: Reached target Bluetooth. Dec 22 13:40:03 volumio3 sudo[628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:03 volumio3 sudo[628]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:03 volumio3 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 22 13:40:03 volumio3 kernel: Bluetooth: BNEP filters: protocol multicast Dec 22 13:40:03 volumio3 kernel: Bluetooth: BNEP socket layer initialized Dec 22 13:40:03 volumio3 dbus[638]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Dec 22 13:40:03 volumio3 bluetoothd[735]: Bluetooth management interface 1.14 initialized Dec 22 13:40:03 volumio3 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 22 13:40:03 volumio3 systemd[1]: Starting Hostname Service... Dec 22 13:40:03 volumio3 systemd[1]: Stopping cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Starting cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Started cd.service. Dec 22 13:40:03 volumio3 volumio[774]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Dec 22 13:40:03 volumio3 systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Dec 22 13:40:03 volumio3 systemd[1]: Unit cd.service entered failed state. Dec 22 13:40:03 volumio3 systemd[1]: cd.service holdoff time over, scheduling restart. Dec 22 13:40:03 volumio3 systemd[1]: Stopping cd.service... Dec 22 13:40:03 volumio3 systemd[1]: Starting cd.service... Dec 22 13:40:03 volumio3 systemd[1]: cd.service start request repeated too quickly, refusing to start. Dec 22 13:40:03 volumio3 systemd[1]: Failed to start cd.service. Dec 22 13:40:03 volumio3 systemd[1]: Unit cd.service entered failed state. Dec 22 13:40:04 volumio3 systemd-hostnamed[773]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Dec 22 13:40:04 volumio3 dbus[638]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 22 13:40:04 volumio3 systemd[1]: Started Hostname Service. Dec 22 13:40:04 volumio3 dbus[638]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Dec 22 13:40:04 volumio3 systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Dec 22 13:40:04 volumio3 polkitd[777]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 22 13:40:04 volumio3 dbus[638]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 22 13:40:04 volumio3 systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Dec 22 13:40:04 volumio3 udisks-glue[584]: Device file /dev/sda inserted Dec 22 13:40:04 volumio3 udisks-glue[584]: Device file /dev/mmcblk0 inserted Dec 22 13:40:04 volumio3 udisks-glue[584]: Device file /dev/mmcblk0p3 inserted Dec 22 13:40:04 volumio3 udisks-glue[584]: Device file /dev/sda1 inserted Dec 22 13:40:04 volumio3 udisks-glue[584]: Trying to automount /dev/sda1... Dec 22 13:40:05 volumio3 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 22 13:40:05 volumio3 udisks-glue[584]: Successfully automounted /dev/sda1 at /media/SSD-PGU5 Dec 22 13:40:05 volumio3 udisks-glue[584]: Device file /dev/sda1 mounted at /media/SSD-PGU5 Dec 22 13:40:06 volumio3 volumio-remote-updater[587]: Error: No active session Dec 22 13:40:06 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:06] [info] asio async_connect error: system:111 (Connection refused) Dec 22 13:40:06 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 22 13:40:06 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:06] [error] handle_connect error: Underlying Transport Error Dec 22 13:40:06 volumio3 volumio[585]: Could not open config: /tmp/upmpdcli.conf Dec 22 13:40:07 volumio3 systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Dec 22 13:40:07 volumio3 systemd[1]: Unit upmpdcli.service entered failed state. Dec 22 13:40:08 volumio3 ntpd[730]: Deferring DNS for 0.debian.pool.ntp.org 1 Dec 22 13:40:08 volumio3 ntpd[730]: Deferring DNS for 1.debian.pool.ntp.org 1 Dec 22 13:40:08 volumio3 ntpd[730]: Deferring DNS for 2.debian.pool.ntp.org 1 Dec 22 13:40:08 volumio3 ntpd[730]: Deferring DNS for 3.debian.pool.ntp.org 1 Dec 22 13:40:08 volumio3 ntpd[793]: signal_no_reset: signal 17 had flags 4000000 Dec 22 13:40:10 volumio3 ntpd_intres[793]: host name not found: 0.debian.pool.ntp.org Dec 22 13:40:10 volumio3 ntpd_intres[793]: host name not found: 1.debian.pool.ntp.org Dec 22 13:40:10 volumio3 ntpd_intres[793]: host name not found: 2.debian.pool.ntp.org Dec 22 13:40:10 volumio3 ntpd_intres[793]: host name not found: 3.debian.pool.ntp.org Dec 22 13:40:11 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:11] [info] asio async_connect error: system:111 (Connection refused) Dec 22 13:40:11 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 22 13:40:11 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:11] [error] handle_connect error: Underlying Transport Error Dec 22 13:40:13 volumio3 wireless.js[771]: Cleaning previous... Dec 22 13:40:13 volumio3 systemd[1]: Stopped hotspot.service. Dec 22 13:40:13 volumio3 sudo[808]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 22 13:40:13 volumio3 sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:13 volumio3 sudo[808]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:13 volumio3 sudo[816]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 22 13:40:13 volumio3 sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:13 volumio3 systemd[1]: Started LSB: start Samba daemons for the AD DC. Dec 22 13:40:13 volumio3 winbind[596]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 22 13:40:13 volumio3 winbind[596]: Unable to setup corepath for winbindd: No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: mkdir failed on directory /var/log/samba/cores: No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 22 13:40:13 volumio3 winbind[596]: Unable to setup corepath for winbindd: No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbind[596]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbind[596]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13, 0] ../source3/winbindd/winbindd.c:1549(main) Dec 22 13:40:13 volumio3 winbind[596]: winbindd version 4.2.14-Debian started. Dec 22 13:40:13 volumio3 winbind[596]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13, 0] ../lib/util/util.c:220(directory_create_or_exist) Dec 22 13:40:13 volumio3 winbind[596]: mkdir failed on directory /var/log/samba/cores: No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Dec 22 13:40:13 volumio3 winbind[596]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Dec 22 13:40:13 volumio3 winbind[596]: Unable to setup corepath for winbindd: No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 22 13:40:13 volumio3 nmbd[600]: Unable to setup corepath for nmbd: No such file or directory Dec 22 13:40:13 volumio3 nmbd[832]: [2020/12/22 13:40:13.861791, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 nmbd[832]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 22 13:40:13 volumio3 winbindd[829]: [2020/12/22 13:40:13.863816, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbindd[829]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 22 13:40:13 volumio3 winbindd[829]: [2020/12/22 13:40:13.864693, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbindd[829]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: [2020/12/22 13:40:13, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: [2020/12/22 13:40:13, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: [2020/12/22 13:40:13, 0] ../source3/nmbd/nmbd.c:908(main) Dec 22 13:40:13 volumio3 nmbd[600]: nmbd version 4.2.14-Debian started. Dec 22 13:40:13 volumio3 nmbd[600]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Dec 22 13:40:13 volumio3 nmbd[600]: [2020/12/22 13:40:13.861791, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[832]: [2020/12/22 13:40:13.866878, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 nmbd[832]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[833]: [2020/12/22 13:40:13.870195, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Dec 22 13:40:13 volumio3 nmbd[833]: started asyncdns process 834 Dec 22 13:40:13 volumio3 systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Dec 22 13:40:13 volumio3 systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13.863816, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbind[596]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 22 13:40:13 volumio3 winbind[596]: [2020/12/22 13:40:13.864693, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbind[596]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: [2020/12/22 13:40:13.866878, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Dec 22 13:40:13 volumio3 nmbd[600]: . Dec 22 13:40:13 volumio3 nmbd[833]: [2020/12/22 13:40:13.900435, 0] ../lib/util/become_daemon.c:135(daemon_status) Dec 22 13:40:13 volumio3 nmbd[833]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 22 13:40:13 volumio3 sudo[816]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:13 volumio3 winbind[596]: . Dec 22 13:40:13 volumio3 systemd[1]: Started LSB: start Winbind daemon. Dec 22 13:40:13 volumio3 winbindd[846]: [2020/12/22 13:40:13.945244, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Dec 22 13:40:13 volumio3 winbindd[846]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 22 13:40:13 volumio3 winbindd[846]: [2020/12/22 13:40:13.989850, 0] ../lib/util/become_daemon.c:124(daemon_ready) Dec 22 13:40:13 volumio3 winbindd[846]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 22 13:40:13 volumio3 winbindd[849]: [2020/12/22 13:40:13.995637, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:13 volumio3 winbindd[849]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO3': No such file or directory Dec 22 13:40:14 volumio3 wireless.js[771]: Stopped aP Dec 22 13:40:14 volumio3 wireless.js[771]: Start wireless flow Dec 22 13:40:14 volumio3 wireless.js[771]: Stopped hotspot (if there).. Dec 22 13:40:14 volumio3 sudo[851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 22 13:40:14 volumio3 sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:14 volumio3 sudo[851]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:14 volumio3 sudo[859]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 22 13:40:14 volumio3 sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:14 volumio3 sudo[859]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:14 volumio3 wireless.js[771]: DHCP IP Dec 22 13:40:14 volumio3 wireless.js[771]: Start ap Dec 22 13:40:14 volumio3 sudo[870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 22 13:40:14 volumio3 sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:14 volumio3 dhcpcd[874]: version 6.0.5 starting Dec 22 13:40:14 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 22 13:40:14 volumio3 dhcpcd[874]: eth0: waiting for carrier Dec 22 13:40:14 volumio3 dhcpcd[874]: wlan0: waiting for carrier Dec 22 13:40:14 volumio3 wpa_supplicant[867]: Successfully initialized wpa_supplicant Dec 22 13:40:14 volumio3 smbd[835]: Starting SMB/CIFS daemon: smbd[2020/12/22 13:40:14, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[835]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14, 0] ../lib/util/util.c:220(directory_create_or_exist) Dec 22 13:40:14 volumio3 smbd[835]: mkdir failed on directory /var/log/samba/cores: No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Dec 22 13:40:14 volumio3 smbd[835]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Dec 22 13:40:14 volumio3 smbd[835]: Unable to setup corepath for smbd: No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[835]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14, 0] ../source3/smbd/server.c:1241(main) Dec 22 13:40:14 volumio3 smbd[835]: smbd version 4.2.14-Debian started. Dec 22 13:40:14 volumio3 smbd[835]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Dec 22 13:40:14 volumio3 smbd[898]: [2020/12/22 13:40:14.758561, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[898]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[898]: [2020/12/22 13:40:14.759858, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[898]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[898]: [2020/12/22 13:40:14.760043, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[898]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14.758561, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[835]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14.759858, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[835]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: [2020/12/22 13:40:14.760043, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[835]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:14 volumio3 smbd[835]: . Dec 22 13:40:14 volumio3 systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Dec 22 13:40:14 volumio3 winbindd[903]: [2020/12/22 13:40:14.863870, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 winbindd[903]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Dec 22 13:40:14 volumio3 smbd[900]: [2020/12/22 13:40:14.895430, 0] ../lib/util/become_daemon.c:124(daemon_ready) Dec 22 13:40:14 volumio3 smbd[900]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 22 13:40:14 volumio3 smbd[904]: [2020/12/22 13:40:14.899328, 0] ../lib/util/debug.c:603(reopen_logs_internal) Dec 22 13:40:14 volumio3 smbd[904]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Dec 22 13:40:15 volumio3 wireless.js[771]: trying... Dec 22 13:40:15 volumio3 sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:15 volumio3 sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:15 volumio3 sudo[907]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:16 volumio3 wireless.js[771]: trying... Dec 22 13:40:16 volumio3 sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:16 volumio3 sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:16 volumio3 sudo[916]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:17 volumio3 wireless.js[771]: trying... Dec 22 13:40:17 volumio3 sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:17 volumio3 sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:17 volumio3 sudo[925]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:18 volumio3 wireless.js[771]: trying... Dec 22 13:40:18 volumio3 sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:18 volumio3 sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:18 volumio3 sudo[934]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:19 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:19] [info] asio async_connect error: system:111 (Connection refused) Dec 22 13:40:19 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 22 13:40:19 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:19] [error] handle_connect error: Underlying Transport Error Dec 22 13:40:19 volumio3 wireless.js[771]: trying... Dec 22 13:40:19 volumio3 sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:19 volumio3 sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:19 volumio3 sudo[943]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:20 volumio3 wireless.js[771]: trying... Dec 22 13:40:20 volumio3 sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:20 volumio3 sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:20 volumio3 sudo[952]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:20 volumio3 systemd[1]: Started Music Player Daemon. Dec 22 13:40:20 volumio3 udisks-glue[584]: Updating DB (#1) ... Dec 22 13:40:20 volumio3 udisks-glue[584]: volume: 40% repeat: off random: off single: off consume: off Dec 22 13:40:21 volumio3 wireless.js[771]: trying... Dec 22 13:40:21 volumio3 sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:21 volumio3 sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:21 volumio3 sudo[963]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:22 volumio3 wireless.js[771]: trying... Dec 22 13:40:22 volumio3 sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:22 volumio3 sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:22 volumio3 sudo[972]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:23 volumio3 wireless.js[771]: trying... Dec 22 13:40:23 volumio3 sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:23 volumio3 sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:23 volumio3 sudo[981]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:24 volumio3 wireless.js[771]: trying... Dec 22 13:40:24 volumio3 sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:24 volumio3 sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:24 volumio3 sudo[990]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:25 volumio3 wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Buffalo-G-E3E8' Dec 22 13:40:25 volumio3 wireless.js[771]: trying... Dec 22 13:40:25 volumio3 sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:25 volumio3 sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:25 volumio3 sudo[999]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:26 volumio3 wireless.js[771]: trying... Dec 22 13:40:26 volumio3 sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:26 volumio3 sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:26 volumio3 sudo[1008]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:27 volumio3 wireless.js[771]: trying... Dec 22 13:40:27 volumio3 sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:27 volumio3 sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:27 volumio3 sudo[1017]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:27 volumio3 wpa_supplicant[899]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Dec 22 13:40:28 volumio3 wireless.js[771]: trying... Dec 22 13:40:28 volumio3 sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:28 volumio3 sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:28 volumio3 sudo[1026]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:29 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:29] [info] asio async_connect error: system:111 (Connection refused) Dec 22 13:40:29 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 22 13:40:29 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:29] [error] handle_connect error: Underlying Transport Error Dec 22 13:40:29 volumio3 dhcpcd[502]: timed out Dec 22 13:40:29 volumio3 dhcpcd[502]: exited Dec 22 13:40:29 volumio3 ifup[468]: dhcpcd[502]: timed out Dec 22 13:40:29 volumio3 ifup[468]: dhcpcd[502]: exited Dec 22 13:40:29 volumio3 ifup[468]: Failed to bring up eth0. Dec 22 13:40:29 volumio3 wireless.js[771]: trying... Dec 22 13:40:29 volumio3 sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:29 volumio3 sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:29 volumio3 sudo[1037]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:30 volumio3 wpa_supplicant[899]: wlan0: Trying to associate with SSID 'Buffalo-G-E3E8' Dec 22 13:40:30 volumio3 wireless.js[771]: trying... Dec 22 13:40:30 volumio3 sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:30 volumio3 sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:30 volumio3 sudo[1046]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:31 volumio3 wireless.js[771]: trying... Dec 22 13:40:31 volumio3 sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:31 volumio3 sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:31 volumio3 sudo[1055]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:32 volumio3 wireless.js[771]: trying... Dec 22 13:40:32 volumio3 sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:32 volumio3 sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:33 volumio3 sudo[1064]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:33 volumio3 wpa_supplicant[899]: wlan0: Associated with 18:c2:bf:d7:e3:e9 Dec 22 13:40:33 volumio3 wpa_supplicant[899]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Dec 22 13:40:33 volumio3 wpa_supplicant[899]: wlan0: WPA: Key negotiation completed with 18:c2:bf:d7:e3:e9 [PTK=CCMP GTK=CCMP] Dec 22 13:40:33 volumio3 wpa_supplicant[899]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:c2:bf:d7:e3:e9 completed [id=0 id_str=] Dec 22 13:40:33 volumio3 dhcpcd[874]: wlan0: carrier acquired Dec 22 13:40:33 volumio3 dhcpcd[874]: wlan0: rebinding lease of 192.168.11.8 Dec 22 13:40:33 volumio3 dhcpcd[874]: wlan0: NAK: requested address not available from 192.168.11.1 Dec 22 13:40:34 volumio3 wireless.js[771]: trying... Dec 22 13:40:34 volumio3 sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:34 volumio3 sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:34 volumio3 sudo[1095]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:34 volumio3 dhcpcd[874]: wlan0: soliciting a DHCP lease Dec 22 13:40:35 volumio3 wireless.js[771]: trying... Dec 22 13:40:35 volumio3 sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 22 13:40:35 volumio3 sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:35 volumio3 sudo[1107]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:35 volumio3 dhcpcd[874]: wlan0: offered 192.168.11.54 from 192.168.11.1 Dec 22 13:40:36 volumio3 wireless.js[771]: Overtime, starting plan B Dec 22 13:40:39 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:39] [info] asio async_connect error: system:111 (Connection refused) Dec 22 13:40:39 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 22 13:40:39 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:39] [error] handle_connect error: Underlying Transport Error Dec 22 13:40:44 volumio3 dhcpcd[874]: timed out Dec 22 13:40:44 volumio3 dhcpcd[874]: forked to background, child pid 1116 Dec 22 13:40:44 volumio3 sudo[870]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:44 volumio3 systemd[1]: Started Wireless Services. Dec 22 13:40:44 volumio3 systemd[1]: Starting Volumio Backend Module... Dec 22 13:40:44 volumio3 systemd[1]: Started Volumio Backend Module. Dec 22 13:40:44 volumio3 systemd[1]: Starting Volumio Streaming Daemon... Dec 22 13:40:44 volumio3 systemd[1]: Started Volumio Streaming Daemon. Dec 22 13:40:44 volumio3 systemd[1]: Starting Multi-User System. Dec 22 13:40:44 volumio3 systemd[1]: Reached target Multi-User System. Dec 22 13:40:44 volumio3 systemd[1]: Starting Graphical Interface. Dec 22 13:40:44 volumio3 systemd[1]: Reached target Graphical Interface. Dec 22 13:40:44 volumio3 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 22 13:40:44 volumio3 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 22 13:40:44 volumio3 systemd[1]: Startup finished in 9.987s (kernel) + 53.561s (userspace) = 1min 3.549s. Dec 22 13:40:45 volumio3 volumio-streaming-daemon[1121]: ############################ Dec 22 13:40:45 volumio3 volumio-streaming-daemon[1121]: # Volumio Streaming Daemon # Dec 22 13:40:45 volumio3 volumio-streaming-daemon[1121]: # Running on port 7777 # Dec 22 13:40:45 volumio3 volumio-streaming-daemon[1121]: ############################ Dec 22 13:40:45 volumio3 volumio-streaming-daemon[1121]: Environment: production Dec 22 13:40:48 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:48 volumio3 volumio[1120]: info: ----- Volumio2 ---- Dec 22 13:40:48 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:48 volumio3 volumio[1120]: info: ----- System startup ---- Dec 22 13:40:48 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:48 volumio3 dhcpcd[1116]: wlan0: leased 192.168.11.54 for 172800 seconds Dec 22 13:40:48 volumio3 avahi-daemon[632]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.54. Dec 22 13:40:48 volumio3 avahi-daemon[632]: New relevant interface wlan0.IPv4 for mDNS. Dec 22 13:40:48 volumio3 dhcpcd[1116]: wlan0: adding host route to 192.168.11.54 via 127.0.0.1 Dec 22 13:40:48 volumio3 avahi-daemon[632]: Registering new address record for 192.168.11.54 on wlan0.IPv4. Dec 22 13:40:48 volumio3 dhcpcd[1116]: wlan0: adding route to 192.168.11.0/24 Dec 22 13:40:48 volumio3 dhcpcd[1116]: wlan0: adding default route via 192.168.11.1 Dec 22 13:40:48 volumio3 nmbd[833]: [2020/12/22 13:40:48.906571, 0] ../lib/util/become_daemon.c:124(daemon_ready) Dec 22 13:40:48 volumio3 nmbd[833]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 22 13:40:49 volumio3 volumio[1120]: info: MYVOLUMIO Environment detected Dec 22 13:40:49 volumio3 volumio-remote-updater[587]: [2020-12-22 13:40:49] [connect] Successful connection Dec 22 13:40:49 volumio3 volumio[1120]: info: Plugin folders cleanup Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning into folder /volumio/app/plugins/ Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning category audio_interface Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning category miscellanea Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning category music_service Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning category plugins.json Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning category system_controller Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning category user_interface Dec 22 13:40:49 volumio3 volumio[1120]: info: Scanning into folder /data/plugins/ Dec 22 13:40:49 volumio3 volumio[1120]: info: Plugin folders cleanup completed Dec 22 13:40:49 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:49 volumio3 volumio[1120]: info: ----- Core plugins startup ---- Dec 22 13:40:49 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:49 volumio3 volumio[1120]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 13:40:49 volumio3 volumio[1120]: info: Adding plugin upnp to MyMusic Plugins Dec 22 13:40:49 volumio3 volumio[1120]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 13:40:49 volumio3 volumio[1120]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 13:40:49 volumio3 volumio[1120]: info: Loading plugins from folder /data/plugins/ Dec 22 13:40:49 volumio3 volumio[1120]: info: Loading plugin "system"... Dec 22 13:40:49 volumio3 volumio[1120]: info: Loading plugin "appearance"... Dec 22 13:40:49 volumio3 ntpd[730]: Listen normally on 3 wlan0 192.168.11.54 UDP 123 Dec 22 13:40:49 volumio3 ntpd[730]: peers refreshed Dec 22 13:40:50 volumio3 volumio[1120]: info: Loading plugin "network"... Dec 22 13:40:50 volumio3 volumio[1120]: info: Refreshing Cached IP Addresses Dec 22 13:40:50 volumio3 sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 13:40:50 volumio3 volumio[1120]: info: Loading plugin "services"... Dec 22 13:40:50 volumio3 volumio[1120]: info: Loading plugin "alsa_controller"... Dec 22 13:40:50 volumio3 sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:50 volumio3 sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 13:40:50 volumio3 sudo[1168]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:50 volumio3 sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:50 volumio3 sudo[1171]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:51 volumio3 volumio[1120]: info: Loading plugin "wizard"... Dec 22 13:40:51 volumio3 volumio[1120]: info: Loading plugin "volumio_command_line_client"... Dec 22 13:40:51 volumio3 volumio[1120]: info: Loading plugin "upnp"... Dec 22 13:40:51 volumio3 volumio[1120]: info: [1608644451088] Starting Upmpd Daemon Dec 22 13:40:51 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 13:40:51 volumio3 volumio[1120]: info: Loading plugin "my_music"... Dec 22 13:40:51 volumio3 volumio[1120]: info: Loading plugin "mpd"... Dec 22 13:40:51 volumio3 volumio[1120]: info: Loading plugin "upnp_browser"... Dec 22 13:40:52 volumio3 ntpd_intres[793]: DNS 0.debian.pool.ntp.org -> 162.159.200.123 Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "networkfs"... Dec 22 13:40:52 volumio3 ntpd_intres[793]: DNS 1.debian.pool.ntp.org -> 139.162.81.45 Dec 22 13:40:52 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "alarm-clock"... Dec 22 13:40:52 volumio3 ntpd_intres[793]: DNS 2.debian.pool.ntp.org -> 129.250.35.251 Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "airplay_emulation"... Dec 22 13:40:52 volumio3 volumio[1120]: info: Starting Shairport Sync Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "last_100"... Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "webradio"... Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "i2s_dacs"... Dec 22 13:40:52 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "volumiodiscovery"... Dec 22 13:40:52 volumio3 volumio[1120]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 13:40:52 volumio3 node[1120]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 13:40:52 volumio3 node[1120]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 13:40:52 volumio3 node[1120]: *** WARNING *** For more information see Dec 22 13:40:52 volumio3 node[1120]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 13:40:52 volumio3 node[1120]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 13:40:52 volumio3 node[1120]: *** WARNING *** For more information see Dec 22 13:40:52 volumio3 volumio[1120]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 13:40:52 volumio3 volumio[1120]: *** WARNING *** For more information see Dec 22 13:40:52 volumio3 volumio[1120]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 13:40:52 volumio3 volumio[1120]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 13:40:52 volumio3 volumio[1120]: *** WARNING *** For more information see Dec 22 13:40:52 volumio3 volumio[1120]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 13:40:52 volumio3 volumio[1120]: Discovery: StartAdv! undefined Dec 22 13:40:52 volumio3 volumio[1120]: Discovery: Started advertising... Volumio3 - undefined Dec 22 13:40:52 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "outputs"... Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "albumart"... Dec 22 13:40:52 volumio3 volumio[1120]: info: Plugin example_plugin is not enabled Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "inputs"... Dec 22 13:40:52 volumio3 volumio[1120]: info: Loading plugin "updater_comm"... Dec 22 13:40:53 volumio3 volumio[1120]: info: Plugin mpdemulation is not enabled Dec 22 13:40:53 volumio3 volumio[1120]: info: Loading plugin "rest_api"... Dec 22 13:40:53 volumio3 volumio[1120]: info: Loading plugin "websocket"... Dec 22 13:40:53 volumio3 volumio[1120]: info: ___________ START PLUGINS ___________ Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 13:40:53 volumio3 volumio[1120]: info: [1608644453239] CoreMusicLibrary::Adding element Media Servers Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:40:53 volumio3 volumio[1120]: Forking 3 albumart workers Dec 22 13:40:53 volumio3 volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:40:53 volumio3 volumio[1120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 13:40:53 volumio3 volumio[1120]: info: [1608644453365] CoreMusicLibrary::Adding element Last_100 Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 13:40:53 volumio3 volumio[1120]: info: [1608644453370] CoreMusicLibrary::Adding element Webradio Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 13:40:53 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:53 volumio3 volumio[1120]: info: ----- MyVolumio plugins startup ---- Dec 22 13:40:53 volumio3 volumio[1120]: info: ------------------------------------------- Dec 22 13:40:53 volumio3 volumio[1120]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 22 13:40:53 volumio3 volumio[1120]: info: Loading i18n strings for locale ja Dec 22 13:40:53 volumio3 volumio[1120]: Updating browse sources language Dec 22 13:40:53 volumio3 volumio[1120]: Cannot find translation for sourceMedia Servers Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::initPlayerControls Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 13:40:53 volumio3 volumio[1120]: info: BOOT COMPLETED Dec 22 13:40:53 volumio3 volumio[1120]: [Metrics] CommandRouter: 5s 560.58ms Dec 22 13:40:53 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:40:53 volumio3 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Dec 22 13:40:54 volumio3 volumio[1120]: Starting albumart workers Dec 22 13:40:54 volumio3 volumio[1120]: Starting albumart workers Dec 22 13:40:54 volumio3 volumio[1120]: Starting albumart workers Dec 22 13:40:58 volumio3 volumio[1120]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 22 13:40:58 volumio3 volumio[1120]: info: CoreCommandRouter::Close All Modals sent Dec 22 13:40:58 volumio3 volumio[1120]: info: CoreCommandRouter::Close All Modals sent Dec 22 13:40:58 volumio3 volumio[1120]: Express server listening on port 3000 Dec 22 13:40:58 volumio3 volumio[1120]: [Metrics] WebUI: 11s 846.66ms Dec 22 13:40:58 volumio3 volumio[1120]: Volumio Calling Home Dec 22 13:40:58 volumio3 volumio[1120]: info: CoreStateMachine::resetVolumioState Dec 22 13:40:58 volumio3 volumio[1120]: info: CoreStateMachine::getcurrentVolume Dec 22 13:40:58 volumio3 volumio[1120]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:40:59 volumio3 volumio[1120]: info: Setting Device type: Raspberry PI Dec 22 13:40:59 volumio3 volumio[1120]: info: MPD running with PID637 Dec 22 13:40:59 volumio3 volumio[1120]: ,establishing connection Dec 22 13:40:59 volumio3 volumio[1120]: info: VolumeController:: Volume=100 Mute =false Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::pushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioPushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::updateTrackBlock Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrackBlock Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 13:40:59 volumio3 volumio[1120]: info: Reloading queue from file Dec 22 13:40:59 volumio3 volumio[1120]: error: updateQueue error: null Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::setRepeat null single undefined Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::pushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioPushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::setRandom null Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::pushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioPushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 13:40:59 volumio3 volumio[1120]: info: Starting Shairport Sync Dec 22 13:40:59 volumio3 volumio[1120]: info: Starting Shairport Sync Dec 22 13:40:59 volumio3 sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:40:59 volumio3 sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:59 volumio3 sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:40:59 volumio3 sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:40:59 volumio3 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 22 13:40:59 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 22 13:40:59 volumio3 volumio[1120]: info: VolumeController:: Volume=100 Mute =false Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreStateMachine::pushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioPushState Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 13:40:59 volumio3 volumio[1120]: Discovery: adding cd004a1c-3693-4036-af77-b199e34e92d6 Dec 22 13:40:59 volumio3 volumio[1120]: info: mDNS: Found device Volumio3 Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:40:59 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 22 13:40:59 volumio3 sudo[1266]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:59 volumio3 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 22 13:40:59 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 22 13:40:59 volumio3 sudo[1268]: pam_unix(sudo:session): session closed for user root Dec 22 13:40:59 volumio3 volumio[1120]: info: Shairport-Sync Started Dec 22 13:40:59 volumio3 volumio[1120]: Error adding Membership: Error: addMembership EINVAL Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 22 13:40:59 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:40:59 volumio3 volumio[1120]: info: Shairport-Sync Started Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 13:40:59 volumio3 volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 22 13:41:00 volumio3 volumio[1120]: Discovery: adding bd260ddb-6259-4cc0-8860-dea9d4b5b944 Dec 22 13:41:00 volumio3 volumio[1120]: info: mDNS: Found device Volumio4 Dec 22 13:41:00 volumio3 ntpd_intres[793]: DNS 3.debian.pool.ntp.org -> 129.250.35.250 Dec 22 13:41:01 volumio3 sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 13:41:01 volumio3 sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:41:01 volumio3 sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 13:41:01 volumio3 sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 22 13:41:01 volumio3 sudo[1322]: pam_unix(sudo:session): session closed for user root Dec 22 13:41:01 volumio3 sudo[1324]: pam_unix(sudo:session): session closed for user root Dec 22 13:41:01 volumio3 volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 22 13:41:01 volumio3 volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 22 13:41:01 volumio3 volumio[1120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:41:01 volumio3 volumio[1120]: { Error: certificate is not yet valid Dec 22 13:41:01 volumio3 volumio[1120]: at TLSSocket. (_tls_wrap.js:1105:38) Dec 22 13:41:01 volumio3 volumio[1120]: at emitNone (events.js:106:13) Dec 22 13:41:01 volumio3 volumio[1120]: at TLSSocket.emit (events.js:208:7) Dec 22 13:41:01 volumio3 volumio[1120]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Dec 22 13:41:01 volumio3 volumio[1120]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Dec 22 13:41:01 volumio3 volumio[1120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:41:02 volumio3 sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2020-12-22 13:40 Dec 22 13:41:02 volumio3 sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"