-- Logs begin at Thu 2016-11-03 17:16:42 UTC, end at Thu 2025-05-22 22:30:16 UTC. -- May 22 22:29:35 AxiomAir-f50e2e kernel: random: crng init done May 22 22:29:35 AxiomAir-f50e2e kernel: random: 7 urandom warning(s) missed due to ratelimiting May 22 22:29:35 AxiomAir-f50e2e kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006 May 22 22:29:35 AxiomAir-f50e2e kernel: usbcore: registered new interface driver brcmfmac May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Time has been changed May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Found device 7800. May 22 22:29:35 AxiomAir-f50e2e kernel: usbcore: registered new interface driver btusb May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Found device /sys/subsystem/bluetooth/devices/hci0. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting Load/Save RF Kill Switch Status... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Load/Save RF Kill Switch Status. May 22 22:29:35 AxiomAir-f50e2e systemd-fsck[325]: fsck.fat 4.1 (2017-01-24) May 22 22:29:35 AxiomAir-f50e2e systemd-fsck[325]: /dev/mmcblk0p1: 170 files, 76128/123044 clusters May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started File System Check on /dev/mmcblk0p1. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Mounting /boot... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Mounted /boot. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target Local File Systems. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting Preprocess NFS configuration... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting Raise network interfaces... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started ifup for eth0. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting Create Volatile Files and Directories... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Preprocess NFS configuration. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target NFS client services. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Create Volatile Files and Directories. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting RPC bind portmap service... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target System Time Synchronized. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 22 22:29:35 AxiomAir-f50e2e ifup[357]: command failed: No such device (-19) May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Update UTMP about System Boot/Shutdown. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target System Initialization. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Listening on mpd.socket. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Listening on D-Bus System Message Bus Socket. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Listening on lircd.socket. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target Sockets. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: apt-daily.timer: Adding 5h 6min 7.843645s random time. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Daily apt download activities. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: apt-daily-upgrade.timer: Adding 52min 41.325785s random time. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Daily apt upgrade and clean activities. May 22 22:29:35 AxiomAir-f50e2e sh[348]: command failed: No such device (-19) May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started Daily Cleanup of Temporary Directories. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target Timers. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Reached target Basic System. May 22 22:29:35 AxiomAir-f50e2e systemd[1]: Started D-Bus System Message Bus. May 22 22:29:35 AxiomAir-f50e2e kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04 May 22 22:29:35 AxiomAir-f50e2e kernel: brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28 May 22 22:29:35 AxiomAir-f50e2e ifup[357]: command failed: No such device (-19) May 22 22:29:35 AxiomAir-f50e2e kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready May 22 22:29:35 AxiomAir-f50e2e dhcpcd[406]: eth0: waiting for carrier May 22 22:29:36 AxiomAir-f50e2e kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 22 22:29:36 AxiomAir-f50e2e dhcpcd[406]: eth0: carrier acquired May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Axiom Led Init. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting Bluetooth service... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting lircd(8) initialization helper tool... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started snapservice.service. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Axiom Updater Service. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Volumio SSH enabler. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Convert IR remotes button presses to mouse movements and clicks. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting dhcpcd on all interfaces... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Volumio Backend Module. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting Login Service... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Roon Bridge. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting Axiom Air HWconfig Service... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting Save/Restore Sound Card State... May 22 22:29:36 AxiomAir-f50e2e dhcpcd[455]: Not running dhcpcd because /etc/network/interfaces May 22 22:29:36 AxiomAir-f50e2e dhcpcd[455]: defines some interfaces that will use a May 22 22:29:36 AxiomAir-f50e2e dhcpcd[455]: DHCP client or static address May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting WPA supplicant... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Volumio Iptables Module. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started RPC bind portmap service. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: dhcpcd.service: Control process exited, code=exited status=6 May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Failed to start dhcpcd on all interfaces. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: dhcpcd.service: Unit entered failed state. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Save/Restore Sound Card State. May 22 22:29:36 AxiomAir-f50e2e dhcpcd[406]: eth0: soliciting a DHCP lease May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Reached target RPC Port Mapper. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Reached target Remote File Systems (Pre). May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Reached target Remote File Systems. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started mount disks automatically with standby. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Found device /sys/subsystem/net/devices/wlan0. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started ifup for wlan0. May 22 22:29:36 AxiomAir-f50e2e kernel: brcmfmac: power management disabled May 22 22:29:36 AxiomAir-f50e2e bash[445]: /usr/local/bin/axiom_ledsetup.sh: line 13: /tmp/networkstatus: No such file or directory May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Axiom Air HWconfig Service. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Found user 'avahi' (UID 106) and group 'avahi' (GID 106). May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Successfully dropped root privileges. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: avahi-daemon 0.6.32 starting up. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Successfully called chroot(). May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Successfully dropped remaining capabilities. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Loading service file /services/volumio.service. May 22 22:29:36 AxiomAir-f50e2e systemd-logind[458]: New seat seat0. May 22 22:29:36 AxiomAir-f50e2e kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready May 22 22:29:36 AxiomAir-f50e2e kernel: brcmfmac: power management disabled May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::ba27:ebff:fef5:e2e. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: New relevant interface eth0.IPv6 for mDNS. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Network interface enumeration completed. May 22 22:29:36 AxiomAir-f50e2e avahi-daemon[452]: Registering new address record for fe80::ba27:ebff:fef5:e2e on eth0.*. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started Login Service. May 22 22:29:36 AxiomAir-f50e2e systemd[1]: Started airplay2.service. May 22 22:29:37 AxiomAir-f50e2e bluetoothd[446]: Bluetooth daemon 5.43 May 22 22:29:37 AxiomAir-f50e2e kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Started Bluetooth service. May 22 22:29:37 AxiomAir-f50e2e bluetoothd[446]: Starting SDP server May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Started BT-Agent. May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Starting BluezALSA proxy... May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Reached target Bluetooth. May 22 22:29:37 AxiomAir-f50e2e kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 22 22:29:37 AxiomAir-f50e2e kernel: Bluetooth: BNEP filters: protocol multicast May 22 22:29:37 AxiomAir-f50e2e kernel: Bluetooth: BNEP socket layer initialized May 22 22:29:37 AxiomAir-f50e2e dbus[386]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' May 22 22:29:37 AxiomAir-f50e2e bluetoothd[446]: Bluetooth management interface 1.14 initialized May 22 22:29:37 AxiomAir-f50e2e bluetoothd[446]: Failed to obtain handles for "Service Changed" characteristic May 22 22:29:37 AxiomAir-f50e2e bluetoothd[446]: Sap driver initialization failed. May 22 22:29:37 AxiomAir-f50e2e bluetoothd[446]: sap-server: Operation not permitted (1) May 22 22:29:37 AxiomAir-f50e2e dbus[386]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' May 22 22:29:37 AxiomAir-f50e2e bash[445]: /usr/local/bin/axiom_ledsetup.sh: line 13: /tmp/networkstatus: No such file or directory May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Starting Disk Manager (legacy version)... May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Starting Hostname Service... May 22 22:29:37 AxiomAir-f50e2e avahi-daemon[452]: Server startup complete. Host name is AxiomAir-f50e2e.local. Local service cookie is 43669182. May 22 22:29:37 AxiomAir-f50e2e dbus[386]: [system] Successfully activated service 'org.freedesktop.UDisks' May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Started Disk Manager (legacy version). May 22 22:29:37 AxiomAir-f50e2e dbus[386]: [system] Successfully activated service 'org.freedesktop.hostname1' May 22 22:29:37 AxiomAir-f50e2e systemd[1]: Started Hostname Service. May 22 22:29:38 AxiomAir-f50e2e dbus[386]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' May 22 22:29:38 AxiomAir-f50e2e systemd[1]: Starting Authorization Manager... May 22 22:29:38 AxiomAir-f50e2e systemd[1]: Started WPA supplicant. May 22 22:29:38 AxiomAir-f50e2e wpa_supplicant[463]: Successfully initialized wpa_supplicant May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: spawn bluetoothctl -a May 22 22:29:38 AxiomAir-f50e2e bt-agent[646]: Warning! /etc/bt-pins.cfg is world readable! May 22 22:29:38 AxiomAir-f50e2e haveged[376]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 6.3.0 CTV); collect: 128K May 22 22:29:38 AxiomAir-f50e2e haveged[376]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 10/40; sz: 15688/72592 May 22 22:29:38 AxiomAir-f50e2e haveged[376]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99843 May 22 22:29:38 AxiomAir-f50e2e haveged[376]: haveged: fills: 0, generated: 0 May 22 22:29:38 AxiomAir-f50e2e bt-agent[646]: Agent registered May 22 22:29:38 AxiomAir-f50e2e bt-agent[646]: Default agent requested May 22 22:29:38 AxiomAir-f50e2e polkitd[667]: started daemon version 0.105 using authority implementation `local' version `0.105' May 22 22:29:38 AxiomAir-f50e2e dbus[386]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 22 22:29:38 AxiomAir-f50e2e systemd[1]: Started Authorization Manager. May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [135B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [84B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [87B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [76B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [55B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [bluetooth]# power on May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [66B blob data] May 22 22:29:38 AxiomAir-f50e2e udisks-glue[474]: Device file /dev/mmcblk0 inserted May 22 22:29:38 AxiomAir-f50e2e udisks-glue[474]: Device file /dev/mmcblk0p3 inserted May 22 22:29:38 AxiomAir-f50e2e udisks-glue[474]: Device file /dev/sda1 inserted May 22 22:29:38 AxiomAir-f50e2e udisks-glue[474]: Trying to automount /dev/sda1... May 22 22:29:38 AxiomAir-f50e2e avahi-daemon[452]: Service "AxiomAir-f50e2e" (/services/volumio.service) successfully established. May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [bluetooth]# discoverable on May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [73B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [bluetooth]# agent NoInputNoOutput May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: Agent is already registered May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [bluetooth]# default-agent May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [71B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [bluetooth]# quit May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [57B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [99B blob data] May 22 22:29:38 AxiomAir-f50e2e bt-set-agent[647]: [50B blob data] May 22 22:29:38 AxiomAir-f50e2e systemd[1]: Started BluezALSA proxy. May 22 22:29:38 AxiomAir-f50e2e systemd[1]: Starting A2DP Playback... May 22 22:29:38 AxiomAir-f50e2e kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 22 22:29:38 AxiomAir-f50e2e udisks-glue[474]: Successfully automounted /dev/sda1 at /media/VERBATIM May 22 22:29:39 AxiomAir-f50e2e udisks-glue[474]: Device file /dev/sda inserted May 22 22:29:39 AxiomAir-f50e2e udisks-glue[474]: Device file /dev/sda1 mounted at /media/VERBATIM May 22 22:29:39 AxiomAir-f50e2e bluetoothd[446]: Endpoint registered: sender=:1.12 path=/A2DP/SBC/Sink/1 May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Received SIGRTMIN+20 from PID 237 (plymouthd). May 22 22:29:39 AxiomAir-f50e2e sh[480]: wlan0=wlan0 May 22 22:29:39 AxiomAir-f50e2e loadcpufreq[473]: Loading cpufreq kernel modules...done (none). May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Started Raise network interfaces. May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Reached target Network. May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting Permit User Sessions... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting Music Player Daemon... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Reached target Network is Online. May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting LSB: Brings up/down network automatically... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting /etc/rc.local Compatibility... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting LSB: Brings up/down network automatically... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting Samba NMB Daemon... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Starting LSB: Start NTP daemon... May 22 22:29:39 AxiomAir-f50e2e systemd[1]: Started Permit User Sessions. May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Simple mixer control 'Digital',0 May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Capabilities: pvolume pswitch May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Playback channels: Front Left - Front Right May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Limits: Playback 0 - 207 May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Mono: May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Front Left: Playback 170 [49%] [-18.50dB] [on] May 22 22:29:39 AxiomAir-f50e2e rc.local[763]: Front Right: Playback 170 [49%] [-18.50dB] [on] May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started /etc/rc.local Compatibility. May 22 22:29:40 AxiomAir-f50e2e dhcpcd[406]: eth0: offered 192.168.50.98 from 192.168.50.1 May 22 22:29:40 AxiomAir-f50e2e cpufrequtils[751]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started LSB: set CPUFreq kernel parameters. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Starting Hold until boot process finishes up... May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Starting Terminate Plymouth Boot Screen... May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Received SIGRTMIN+21 from PID 237 (plymouthd). May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Hold until boot process finishes up. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Terminate Plymouth Boot Screen. May 22 22:29:40 AxiomAir-f50e2e ifplugd(eth0)[813]: ifplugd 0.28 initializing. May 22 22:29:40 AxiomAir-f50e2e ifplugd(eth0)[813]: Using interface eth0/B8:27:EB:F5:0E:2E with driver (version: 1.0.6) May 22 22:29:40 AxiomAir-f50e2e ifplugd(eth0)[813]: Using detection mode: SIOCETHTOOL May 22 22:29:40 AxiomAir-f50e2e ifplugd(eth0)[813]: Initialization complete, link beat detected. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Getty on tty1. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Reached target Login Prompts. May 22 22:29:40 AxiomAir-f50e2e ntp[768]: Starting NTP server: ntpderror: unexpectedly disconnected from boot status daemon May 22 22:29:40 AxiomAir-f50e2e netplugd[822]: /etc/netplug/netplug eth0 in -> pid 822 May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started LSB: Brings up/down network automatically. May 22 22:29:40 AxiomAir-f50e2e ifplugd(eth0)[813]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 22 22:29:40 AxiomAir-f50e2e ifplugd(eth0)[813]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started lircd(8) initialization helper tool. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Flexible IR remote input/output application support. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Forward LIRC button presses as uinput events. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Flexible IR remote input/output application support. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: lircd: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e irexec[834]: do_connect: could not connect to socket May 22 22:29:40 AxiomAir-f50e2e irexec[834]: connect: No such file or directory May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: lircd: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e systemd[1]: irexec-devinput.service: Main process exited, code=exited, status=1/FAILURE May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[832]: Info: lircd-uinput: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e lircd-uinput[832]: lircd-0.9.4c[832]: Info: lircd-uinput: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e lircd-uinput[832]: lircd-0.9.4c[832]: Info: Reading data from /var/run/lirc/lircd, writing to /dev/uinput May 22 22:29:40 AxiomAir-f50e2e lircd-uinput[832]: lircd-0.9.4c[832]: Info: Using "_UP" as release suffix May 22 22:29:40 AxiomAir-f50e2e systemd[1]: irexec-devinput.service: Unit entered failed state. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[832]: Info: Reading data from /var/run/lirc/lircd, writing to /dev/uinput May 22 22:29:40 AxiomAir-f50e2e systemd[1]: irexec-devinput.service: Failed with result 'exit-code'. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[832]: Info: Using "_UP" as release suffix May 22 22:29:40 AxiomAir-f50e2e dhcpcd[406]: eth0: leased 192.168.50.98 for 86400 seconds May 22 22:29:40 AxiomAir-f50e2e avahi-daemon[452]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.98. May 22 22:29:40 AxiomAir-f50e2e dhcpcd[406]: eth0: adding route to 192.168.50.0/24 May 22 22:29:40 AxiomAir-f50e2e avahi-daemon[452]: New relevant interface eth0.IPv4 for mDNS. May 22 22:29:40 AxiomAir-f50e2e dhcpcd[406]: eth0: adding default route via 192.168.50.1 May 22 22:29:40 AxiomAir-f50e2e avahi-daemon[452]: Registering new address record for 192.168.50.98 on eth0.IPv4. May 22 22:29:40 AxiomAir-f50e2e kernel: input: lircd-uinput as /devices/virtual/input/input2 May 22 22:29:40 AxiomAir-f50e2e dhcpcd[406]: forked to background, child pid 862 May 22 22:29:40 AxiomAir-f50e2e ntpd[816]: ntpd 4.2.8p10@1.3728-o Sat Mar 10 18:03:33 UTC 2018 (1): Starting May 22 22:29:40 AxiomAir-f50e2e ntpd[816]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:103 May 22 22:29:40 AxiomAir-f50e2e ntp[768]: . May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started LSB: Start NTP daemon. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: Initial device: /dev/lirc0 May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Info: lircd: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: Using systemd fd May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Warning: Running as root May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Info: lircd: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Warning: Running as root May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: Initial device: /dev/lirc0 May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: [lirc] protocol is enabled May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: lircd: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: Using systemd fd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Warning: Running as root May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: Initial device: /dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: Initial device: /dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: lircd: Opening log, level: Info May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Warning: Running as root May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_GAMEPAD May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_GAMEPAD May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_GAMEPAD May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e bash[445]: /usr/local/bin/axiom_ledsetup.sh: line 13: /tmp/networkstatus: No such file or directory May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Warning: Duplicate remotes "devinput" found, problems ahead May 22 22:29:40 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd_devinput May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_GAMEPAD May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: /etc/lirc/lircd_devinput.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Warning: Duplicate remotes "devinput" found, problems ahead May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd_devinput May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: Using remote: devinput. May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Info: Using remote: 2wire. May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: Using remote: 2wire. May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Warning: Duplicate remotes "devinput" found, problems ahead May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Info: [lirc] protocol is enabled May 22 22:29:40 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Warning: Duplicate remotes "devinput" found, problems ahead May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Info: [lirc] protocol is enabled May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: proto: precision = 0.729 usec (-20) May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listen and drop on 0 v6wildcard [::]:123 May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 22 22:29:40 AxiomAir-f50e2e netplugd[821]: wlan0: state DOWN flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001002 BROADCAST,MULTICAST May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listen normally on 2 lo 127.0.0.1:123 May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listen normally on 3 eth0 192.168.50.98:123 May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listen normally on 4 lo [::1]:123 May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listen normally on 5 eth0 [fe80::ba27:ebff:fef5:e2e%2]:123 May 22 22:29:40 AxiomAir-f50e2e ntpd[874]: Listening on routing socket on fd #22 for interface updates May 22 22:29:40 AxiomAir-f50e2e netplugd[821]: wlan0: state DOWN flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST May 22 22:29:40 AxiomAir-f50e2e kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready May 22 22:29:40 AxiomAir-f50e2e kernel: brcmfmac: power management disabled May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Started airplay.service. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Starting dhcpcd on all interfaces... May 22 22:29:40 AxiomAir-f50e2e dhcpcd[895]: Not running dhcpcd because /etc/network/interfaces May 22 22:29:40 AxiomAir-f50e2e dhcpcd[895]: defines some interfaces that will use a May 22 22:29:40 AxiomAir-f50e2e dhcpcd[895]: DHCP client or static address May 22 22:29:40 AxiomAir-f50e2e systemd[1]: dhcpcd.service: Control process exited, code=exited status=6 May 22 22:29:40 AxiomAir-f50e2e systemd[1]: Failed to start dhcpcd on all interfaces. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: dhcpcd.service: Unit entered failed state. May 22 22:29:40 AxiomAir-f50e2e systemd[1]: dhcpcd.service: Failed with result 'exit-code'. May 22 22:29:40 AxiomAir-f50e2e netplugd[821]: eth0: state INNING pid 822 exited status 0 May 22 22:29:41 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 216.128.178.20 May 22 22:29:41 AxiomAir-f50e2e systemd[1]: Started A2DP Playback. May 22 22:29:42 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 162.159.200.1 May 22 22:29:42 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 162.159.200.123 May 22 22:29:42 AxiomAir-f50e2e nmbd[766]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 22 22:29:42 AxiomAir-f50e2e nmbd[766]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 22 22:29:42 AxiomAir-f50e2e nmbd[766]: Unable to setup corepath for nmbd: No such file or directory May 22 22:29:42 AxiomAir-f50e2e nmbd[766]: [2025/05/22 22:29:42.985964, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:42 AxiomAir-f50e2e nmbd[766]: Unable to open new log file '/var/log/samba/log.': No such file or directory May 22 22:29:43 AxiomAir-f50e2e nmbd[766]: [2025/05/22 22:29:43.003275, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:43 AxiomAir-f50e2e nmbd[766]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 22 22:29:43 AxiomAir-f50e2e systemd[1]: nmbd.service: Supervising process 919 which is not our child. We'll most likely not notice when it exits. May 22 22:29:43 AxiomAir-f50e2e systemd[1]: Started Samba NMB Daemon. May 22 22:29:43 AxiomAir-f50e2e systemd[1]: Starting Samba Winbind Daemon... May 22 22:29:43 AxiomAir-f50e2e bash[459]: 00:00:00.010 Trace: [childprocess] using unix child process May 22 22:29:43 AxiomAir-f50e2e ifplugd(eth0)[813]: client: ifup: interface eth0 already configured May 22 22:29:43 AxiomAir-f50e2e sh[348]: eth0=eth0 May 22 22:29:43 AxiomAir-f50e2e ifplugd(eth0)[813]: Program executed successfully. May 22 22:29:43 AxiomAir-f50e2e ifplugd[764]: Network Interface Plugging Daemon...start eth0...done. May 22 22:29:43 AxiomAir-f50e2e systemd[1]: Started LSB: Brings up/down network automatically. May 22 22:29:43 AxiomAir-f50e2e bash[459]: Initializing May 22 22:29:43 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 70.55.19.46 May 22 22:29:43 AxiomAir-f50e2e snapservice[449]: Starting Server Watch May 22 22:29:43 AxiomAir-f50e2e snapservice[449]: Adding watch for file Server May 22 22:29:43 AxiomAir-f50e2e snapservice[449]: Starting Client Watch May 22 22:29:43 AxiomAir-f50e2e snapservice[449]: Adding watch for file Client May 22 22:29:43 AxiomAir-f50e2e updater[451]: Update URL set to :https://s3.amazonaws.com/axiom-air-install-files/AxiomAirV2/updates/ May 22 22:29:43 AxiomAir-f50e2e bash[459]: 00:00:00.965 Info: Starting /usr/local/RoonBridge/Bridge/RoonBridgeHelper May 22 22:29:43 AxiomAir-f50e2e bash[459]: 00:00:00.975 Info: ConnectOrStartAndWaitForExit RAATServer May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: Unable to setup corepath for winbindd: No such file or directory May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: Unable to setup corepath for winbindd: No such file or directory May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: [2025/05/22 22:29:43.924510, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: [2025/05/22 22:29:43.925999, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:43 AxiomAir-f50e2e winbindd[920]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 22 22:29:43 AxiomAir-f50e2e systemd[1]: winbind.service: Supervising process 971 which is not our child. We'll most likely not notice when it exits. May 22 22:29:44 AxiomAir-f50e2e systemd[1]: Started Samba Winbind Daemon. May 22 22:29:44 AxiomAir-f50e2e systemd[1]: Starting Samba SMB Daemon... May 22 22:29:44 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 54.39.196.172 May 22 22:29:44 AxiomAir-f50e2e bash[459]: Not Running (.o) May 22 22:29:44 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 167.114.93.151 May 22 22:29:44 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 51.79.69.205 May 22 22:29:44 AxiomAir-f50e2e smbd[976]: [2025/05/22 22:29:44.859120, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:44 AxiomAir-f50e2e smbd[976]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 22 22:29:44 AxiomAir-f50e2e smbd[976]: [2025/05/22 22:29:44.862011, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:44 AxiomAir-f50e2e smbd[976]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 22 22:29:44 AxiomAir-f50e2e smbd[976]: [2025/05/22 22:29:44.862317, 0] ../lib/util/debug.c:951(reopen_logs_internal) May 22 22:29:44 AxiomAir-f50e2e smbd[976]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 22 22:29:44 AxiomAir-f50e2e systemd[1]: smbd.service: Supervising process 986 which is not our child. We'll most likely not notice when it exits. May 22 22:29:45 AxiomAir-f50e2e systemd[1]: Started Samba SMB Daemon. May 22 22:29:45 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 149.56.19.163 May 22 22:29:45 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 23.133.168.244 May 22 22:29:46 AxiomAir-f50e2e bash[459]: Running May 22 22:29:46 AxiomAir-f50e2e systemd[1]: Started Music Player Daemon. May 22 22:29:46 AxiomAir-f50e2e systemd[1]: Reached target Multi-User System. May 22 22:29:46 AxiomAir-f50e2e systemd[1]: Reached target Graphical Interface. May 22 22:29:46 AxiomAir-f50e2e systemd[1]: Starting Update UTMP about System Runlevel Changes... May 22 22:29:46 AxiomAir-f50e2e udisks-glue[474]: Updating DB (#1) ... May 22 22:29:46 AxiomAir-f50e2e udisks-glue[474]: volume: n/a repeat: off random: off single: off consume: off May 22 22:29:46 AxiomAir-f50e2e systemd[1]: Started Update UTMP about System Runlevel Changes. May 22 22:29:46 AxiomAir-f50e2e systemd[1]: Startup finished in 9.863s (kernel) + 14.596s (userspace) = 24.460s. May 22 22:29:46 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 173.183.146.26 May 22 22:29:46 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 206.108.0.132 May 22 22:29:47 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 216.232.132.102 May 22 22:29:47 AxiomAir-f50e2e ntpd[874]: Soliciting pool server 2607:5300:205:200::577b May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: ------------------------------------------- May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: ----- Volumio2 ---- May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: ------------------------------------------- May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: ----- System startup ---- May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: ------------------------------------------- May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Plugin folders cleanup May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning into folder /volumio/app/plugins/ May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category accessory May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category audio_interface May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category miscellanea May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category music_service May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category plugins.json May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category system_controller May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning category user_interface May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Scanning into folder /data/plugins/ May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Plugin folders cleanup completed May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Loading plugins from folder /volumio/app/plugins/ May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Loading plugins from folder /data/plugins/ May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Loading plugin "system"... May 22 22:29:48 AxiomAir-f50e2e volumio[456]: info: Loading plugin "appearance"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "network"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "services"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "alsa_controller"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "wizard"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "volumio_command_line_client"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "upnp"... May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: [1747952990852] Starting Upmpd Daemon May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 22:29:50 AxiomAir-f50e2e volumio[456]: info: Loading plugin "mpd"... May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: Loading plugin "upnp_browser"... May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: airplayd v. 1.3.10 May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: pid: 1151 May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: 2025-05-22 10:29:51.208795 PM [AirPlay] ### AirPlay Audio Receiver POSIX Release Version: 211.1.p8 May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: 2025-05-22 10:29:51.314619 PM [AirPlay] Name changed '' -> 'AxiomAir-f50e2e' May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: 2025-05-22 10:29:51.327741 PM [AirPlay] Registering Bonjour _airplay._tcp. port 5000 May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: 2025-05-22 10:29:51.342448 PM [AirPlay] Registering Bonjour B827EBF50E2E@AxiomAir-f50e2e._raop._tcp. port 5000 May 22 22:29:51 AxiomAir-f50e2e volumio[1151]: 2025-05-22 10:29:51.342579 PM [AirPlay] AirPlay servers started May 22 22:29:51 AxiomAir-f50e2e root[1152]: [2025/05/22 22:29:51:4263] NOTICE: Message Thread is 0x71b4d1e0 May 22 22:29:51 AxiomAir-f50e2e root[1152]: [2025/05/22 22:29:51:4349] NOTICE: Ping Thread is 0x711ff1e0 May 22 22:29:51 AxiomAir-f50e2e root[1152]: *** WARNING *** The program 'AirPlayService' uses the Apple Bonjour compatibility layer of Avahi. May 22 22:29:51 AxiomAir-f50e2e root[1152]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 22:29:51 AxiomAir-f50e2e root[1152]: *** WARNING *** For more information see May 22 22:29:51 AxiomAir-f50e2e AirPlayService[1152]: *** WARNING *** The program 'AirPlayService' uses the Apple Bonjour compatibility layer of Avahi. May 22 22:29:51 AxiomAir-f50e2e AirPlayService[1152]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 22:29:51 AxiomAir-f50e2e AirPlayService[1152]: *** WARNING *** For more information see May 22 22:29:51 AxiomAir-f50e2e root[1152]: 2025-05-22 10:29:51.556429 PM [AirPlay] Registering Bonjour _airplay._tcp. port 7000 May 22 22:29:51 AxiomAir-f50e2e root[1152]: 2025-05-22 10:29:51.567672 PM [AirPlay] Registering Bonjour B827EBF50E2E@AxiomAir-f50e2e-AP2._raop._tcp. port 7000 May 22 22:29:51 AxiomAir-f50e2e root[1152]: 2025-05-22 10:29:51.567792 PM [AirPlay] AirPlay servers started May 22 22:29:50 AxiomAir-f50e2e systemd[1]: Time has been changed May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.bbc57d48 does not match aorg 0000000000.00000000 from server@206.108.0.132 xmt 0xebda23de.afe8fe8e May 22 22:29:50 AxiomAir-f50e2e systemd[1]: apt-daily.timer: Adding 39min 29.860030s random time. May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.bbd2ce63 does not match aorg 0000000000.00000000 from server@51.79.69.205 xmt 0xebda23de.b0d74fa4 May 22 22:29:50 AxiomAir-f50e2e systemd[1]: apt-daily-upgrade.timer: Adding 24min 49.631137s random time. May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.bbd6142b does not match aorg 0000000000.00000000 from server@167.114.93.151 xmt 0xebda23de.b2540cc6 May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.bbd92818 does not match aorg 0000000000.00000000 from server@54.39.196.172 xmt 0xebda23de.b213917a May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.bbce687d does not match aorg 0000000000.00000000 from server@23.133.168.244 xmt 0xebda23de.b25d1e64 May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.b5440010 does not match aorg 0000000000.00000000 from server@216.232.132.102 xmt 0xebda23de.b1355682 May 22 22:29:50 AxiomAir-f50e2e ntpd[874]: receive: Unexpected origin timestamp 0xebda23df.b8a0792d does not match aorg 0000000000.00000000 from server@173.183.146.26 xmt 0xebda23de.b36c506a May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: Loading plugin "networkfs"... May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: Loading plugin "alarm-clock"... May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: Loading plugin "last_100"... May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: Loading plugin "webradio"... May 22 22:29:51 AxiomAir-f50e2e volumio[456]: info: Loading plugin "gpio-buttons"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Command failed: /usr/local/bin/karaokectl read May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Unable to read levels. Using legacy Karaoke mode May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons initialized May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "i2s_dacs"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: I2S DAC not set, start Auto-detection May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "volumiodiscovery"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 22:29:52 AxiomAir-f50e2e volumio[456]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 22:29:52 AxiomAir-f50e2e volumio[456]: *** WARNING *** For more information see May 22 22:29:52 AxiomAir-f50e2e volumio[456]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 22:29:52 AxiomAir-f50e2e volumio[456]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 22:29:52 AxiomAir-f50e2e volumio[456]: *** WARNING *** For more information see May 22 22:29:52 AxiomAir-f50e2e node[456]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 22:29:52 AxiomAir-f50e2e node[456]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 22:29:52 AxiomAir-f50e2e node[456]: *** WARNING *** For more information see May 22 22:29:52 AxiomAir-f50e2e node[456]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 22:29:52 AxiomAir-f50e2e node[456]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 22:29:52 AxiomAir-f50e2e node[456]: *** WARNING *** For more information see May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 22:29:52 AxiomAir-f50e2e volumio[456]: Discovery: StartAdv! undefined May 22 22:29:52 AxiomAir-f50e2e volumio[456]: Discovery: Started advertising... AxiomAir-f50e2e - undefined May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "ir_controller"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Applying required configuration parameters for plugin ir_controller May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "multiroom"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , registerCallback May 22 22:29:52 AxiomAir-f50e2e volumio[456]: error: ATTENTION!!!: Plugin multiroom does not return adequate promise from onVolumioStart: please update! May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "albumart"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "airplay2"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: AirPlay Plugin booting up.. May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "airplay_emulation"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: AirPlay Plugin booting up.. May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Adding watch for file /tmp/status May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Adding watch for file /tmp/volume May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Adding watch for file /tmp/progress May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Plugin example_plugin is not enabled May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "personal_radio"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: ControllerPersonalRadio::constructor May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "spop"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Applying required configuration parameters for plugin spop May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "tidal"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Applying required configuration parameters for plugin tidal May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "tunein_radio"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Applying required configuration parameters for plugin tunein_radio May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "volspotconnect2"... May 22 22:29:52 AxiomAir-f50e2e volumio[456]: info: Loading plugin "updater_comm"... May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: Plugin mpdemulation is not enabled May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: Loading plugin "rest_api"... May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: Loading plugin "websocket"... May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: ___________ START PLUGINS ___________ May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: appearance May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: wizard May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: upnp_browser May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: [1747952993126] CoreMusicLibrary::Adding element Media Servers May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: last_100 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: [1747952993155] CoreMusicLibrary::Adding element Last_100 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: webradio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: [1747952993164] CoreMusicLibrary::Adding element Webradio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: gpio-buttons May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: Registering battery monitor May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons: Reading config and creating triggers... May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons: playPause on pin 498 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons: volumeUp on pin 499 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons: volumeDown on pin 497 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons: next on pin 496 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons: shutdown on pin 4 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: ir_controller May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: multiroom May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: personal_radio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: [1747952993346] CoreMusicLibrary::Adding element Personal Radio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: spop May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: tidal May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: tunein_radio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: TuneIn addToBrowseSources May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: [1747952993433] CoreMusicLibrary::Adding element TuneIn Radio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: $ref=$, _isPromise=true, _successFn=undefined, _failFn=undefined, $ref=$, _boundArgs=null, _hasContext=false, _nextContext=undefined, _currentContext=undefined May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: PLUGIN START: volspotconnect2 May 22 22:29:53 AxiomAir-f50e2e sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/lirc/lircd.conf.d May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: Loading i18n strings for locale en May 22 22:29:53 AxiomAir-f50e2e sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 22 22:29:53 AxiomAir-f50e2e sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:53 AxiomAir-f50e2e sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 22 22:29:53 AxiomAir-f50e2e volumio[456]: Updating browse sources language May 22 22:29:53 AxiomAir-f50e2e sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:53 AxiomAir-f50e2e volumio[456]: Cannot find translation for sourceMedia Servers May 22 22:29:53 AxiomAir-f50e2e volumio[456]: Cannot find translation for sourcePersonal Radio May 22 22:29:53 AxiomAir-f50e2e volumio[456]: Cannot find translation for sourceTuneIn Radio May 22 22:29:53 AxiomAir-f50e2e sudo[1317]: pam_unix(sudo:session): session closed for user root May 22 22:29:53 AxiomAir-f50e2e sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::initPlayerControls May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:53 AxiomAir-f50e2e sudo[1319]: pam_unix(sudo:session): session closed for user root May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 22:29:53 AxiomAir-f50e2e systemd[1]: Started Volspotconnect2 Daemon. May 22 22:29:53 AxiomAir-f50e2e sudo[1321]: pam_unix(sudo:session): session closed for user root May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: BOOT COMPLETED May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::Close All Modals sent May 22 22:29:53 AxiomAir-f50e2e volumio[456]: Express server listening on port 3000 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: Volumio Calling Home May 22 22:29:53 AxiomAir-f50e2e kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: (node:456) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: GPIO-Buttons started May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::resetVolumioState May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getcurrentVolume May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioRetrievevolume May 22 22:29:53 AxiomAir-f50e2e volumio[1339]: vollibrespot v0.1.3 48346b7 2019-02-25 (librespot 57c9ab5 2019-02-22) -- Built On 2019-02-25 May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::updateTrackBlock May 22 22:29:53 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrackBlock May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: Setting Device type: Raspberry PI May 22 22:29:54 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Vollibrespot Daemon Started May 22 22:29:54 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Starting metadata listener May 22 22:29:54 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] FE => [ 1, [length]: 1 ] May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: The following error occurred while starting SPOPD: Error: Command failed: /usr/bin/sudo /bin/systemctl start spop.service May 22 22:29:54 AxiomAir-f50e2e volumio[456]: Failed to start spop.service: Unit spop.service not found. May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: lirc permissions set May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: MPD running with PID761 ,establishing connection May 22 22:29:54 AxiomAir-f50e2e sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 22 22:29:54 AxiomAir-f50e2e sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:54 AxiomAir-f50e2e sudo[1401]: pam_unix(sudo:session): session closed for user root May 22 22:29:54 AxiomAir-f50e2e root[1152]: [2025/05/22 22:29:54:4402] USER: callback_volumio_increment: established May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: lirc correctly updated May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: VolumeController:: Volume=49 Mute =false May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - stop, service - mpd May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: Volumio called home May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: Reloading queue from file May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::setRepeat null single undefined May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - stop, service - mpd May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::setRandom null May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - stop, service - mpd May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:29:54 AxiomAir-f50e2e updater[451]: Connected May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/VERBATIM/IAN/(Sittin' On) The Dock Of The Bay.m4a","favourite":false} May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/VERBATIM/IAN/(Sittin' On) The Dock Of The Bay.m4a","favourite":false} May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: May 22 22:29:54 AxiomAir-f50e2e volumio[456]: ---------------------------- Client requests Volume 50 May 22 22:29:54 AxiomAir-f50e2e volumio[456]: info: VolumeController::SetAlsaVolume50 May 22 22:29:54 AxiomAir-f50e2e volumio[456]: amixer -M set -c 0 "Digital" 50% May 22 22:29:55 AxiomAir-f50e2e volumio[456]: null May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: VolumeController::Volume 50 May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - stop, service - mpd May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: Setting volume on startup at 50 May 22 22:29:55 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/VERBATIM/IAN/(Sittin' On) The Dock Of The Bay.m4a","favourite":false} May 22 22:29:55 AxiomAir-f50e2e sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service May 22 22:29:55 AxiomAir-f50e2e sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:55 AxiomAir-f50e2e lircd[831]: lircd-0.9.4c[831]: Notice: caught signal May 22 22:29:55 AxiomAir-f50e2e lircd-0.9.4c[831]: Notice: caught signal May 22 22:29:55 AxiomAir-f50e2e systemd[1]: Stopping Flexible IR remote input/output application support... May 22 22:29:55 AxiomAir-f50e2e systemd[1]: Stopped Flexible IR remote input/output application support. May 22 22:29:55 AxiomAir-f50e2e sudo[1434]: pam_unix(sudo:session): session closed for user root May 22 22:29:56 AxiomAir-f50e2e sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lircd.service May 22 22:29:56 AxiomAir-f50e2e sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:56 AxiomAir-f50e2e systemd[1]: Starting lircd(8) initialization helper tool... May 22 22:29:56 AxiomAir-f50e2e systemd[1]: Started lircd(8) initialization helper tool. May 22 22:29:56 AxiomAir-f50e2e systemd[1]: Started Flexible IR remote input/output application support. May 22 22:29:56 AxiomAir-f50e2e sudo[1482]: pam_unix(sudo:session): session closed for user root May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: lircd: Opening log, level: Info May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: Initial device: /dev/lirc0 May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: [lirc] protocol is enabled May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Info: lircd: Opening log, level: Info May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: Using systemd fd May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Warning: Running as root May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Info: Using remote: devinput. May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: Initial device: /dev/lirc0 May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: [lirc] protocol is enabled May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: lircd: Opening log, level: Info May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: Using systemd fd May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Warning: Running as root May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: Using remote: devinput. May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Info: Using remote: devinput. May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: Using remote: devinput. May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MISC May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_MOUSE May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_SOUTH May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TOOL_PEN May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Info: Using remote: 2wire. May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput: Multiple values for same code: BTN_WHEEL May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: Using remote: 2wire. May 22 22:29:56 AxiomAir-f50e2e sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start irexec.service May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Warning: Duplicate remotes "devinput" found, problems ahead May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Warning: Duplicate remotes "devinput" found, problems ahead May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 22 22:29:56 AxiomAir-f50e2e sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:56 AxiomAir-f50e2e systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:56 AxiomAir-f50e2e lircd[1499]: lircd-0.9.4c[1499]: Info: [lirc] protocol is enabled May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Notice: accepted new client on /var/run/lirc/lircd May 22 22:29:56 AxiomAir-f50e2e lircd-0.9.4c[1499]: Info: [lirc] protocol is enabled May 22 22:29:56 AxiomAir-f50e2e sudo[1504]: pam_unix(sudo:session): session closed for user root May 22 22:29:56 AxiomAir-f50e2e volumio[456]: info: lirc correctly started May 22 22:29:59 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::Close All Modals sent May 22 22:29:59 AxiomAir-f50e2e systemd[1]: irexec-devinput.service: Service hold-off time over, scheduling restart. May 22 22:29:59 AxiomAir-f50e2e systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). May 22 22:29:59 AxiomAir-f50e2e systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 22 22:29:59 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Notice: accepted new client on /var/run/lirc/lircd_devinput May 22 22:29:59 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Info: initializing '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:29:59 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Info: Using device: /dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd May 22 22:29:59 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:29:59 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Warning: Failed to initialize hardware May 22 22:29:59 AxiomAir-f50e2e lircd-0.9.4c[833]: Notice: accepted new client on /var/run/lirc/lircd_devinput May 22 22:29:59 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: initializing '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:29:59 AxiomAir-f50e2e lircd-0.9.4c[833]: Info: Using device: /dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd May 22 22:29:59 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:29:59 AxiomAir-f50e2e lircd-0.9.4c[833]: Warning: Failed to initialize hardware May 22 22:29:59 AxiomAir-f50e2e sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 22:29:59 AxiomAir-f50e2e sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:59 AxiomAir-f50e2e sudo[1527]: pam_unix(sudo:session): session closed for user root May 22 22:29:59 AxiomAir-f50e2e sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 22 22:29:59 AxiomAir-f50e2e sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 22:29:59 AxiomAir-f50e2e systemd[1]: Started UPnP Renderer front-end to MPD. May 22 22:29:59 AxiomAir-f50e2e sudo[1531]: pam_unix(sudo:session): session closed for user root May 22 22:29:59 AxiomAir-f50e2e volumio[456]: info: Upmpdcli Daemon Started May 22 22:30:00 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 22 22:30:00 AxiomAir-f50e2e volumio[1541]: Generating RSA private key, 4096 bit long modulus May 22 22:30:00 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:00 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:01 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:01 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:02 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:02 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:02 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:02.789059 PM [AirPlay] Control pair-verify (192.168.50.243:49376) 1 May 22 22:30:02 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:02.789176 PM [AirPlay] pair-verify useHomeKitPairing 1 pairingType 6 May 22 22:30:02 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:02.865321 PM [AirPlay] Control pair-setup HK (192.168.50.243:49376), type 4 May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.045798 PM [AirPlay] Control pair-setup HK (192.168.50.243:49376), type 4 May 22 22:30:03 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Connecting to AP "ap-gue1.spotify.com:4070" May 22 22:30:03 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:03 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.530699 PM [Airtunesd] Received Client IP Update Message May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.530807 PM [Airtunesd] Unsupported session copy property request: rtpSkewPlatformAdjust May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.534503 PM [AirPlay] Updated Bonjour TXT for _airplay._tcp. May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.536647 PM [AirPlay] Updated Bonjour TXT for _raop._tcp. May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.536802 PM [Airtunesd] Unsupported session copy property request: ptpPowerState May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.536898 PM [Airtunesd] Received and Unknown Update Message May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.537227 PM [AirPlay] _GeneralAudioAddIPAddrs: our ptp info: { May 22 22:30:03 AxiomAir-f50e2e root[1152]: "SupportsClockPortMatchingOverride" : false May 22 22:30:03 AxiomAir-f50e2e root[1152]: "Addresses" : May 22 22:30:03 AxiomAir-f50e2e root[1152]: [ May 22 22:30:03 AxiomAir-f50e2e root[1152]: "192.168.50.98" May 22 22:30:03 AxiomAir-f50e2e root[1152]: ] May 22 22:30:03 AxiomAir-f50e2e root[1152]: "ID" : "1ae4a3ea-9ca8-4c14-9414-ecec7aa7262f" May 22 22:30:03 AxiomAir-f50e2e root[1152]: } May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.695695 PM [AirPlay] _requestProcessGetParameter:server volume set to 0.0 May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.704593 PM [AirPlay] AirPlay;2.0.4(28.0) master session started from 192.168.50.243:49376, 0/0x0 noErr May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.781150 PM [AirPlay] AirPlayReceiverSessionSetPeers: [ May 22 22:30:03 AxiomAir-f50e2e root[1152]: "192.168.50.243", May 22 22:30:03 AxiomAir-f50e2e root[1152]: "fe80::14ad:c1e0:b203:6877" May 22 22:30:03 AxiomAir-f50e2e root[1152]: ] May 22 22:30:03 AxiomAir-f50e2e root[1152]: on eth0 May 22 22:30:03 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:03.812786 PM [AirTunesAvnuClock] PTPClockLaunch: launched PID 1592 May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.013139 PM [AirTunesAvnuClock] PTPClockOpenMsgPort May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.013288 PM [AirTunesAvnuClock] Device at ip address 192.168.50.243 is added into the clock group. May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.050984 PM [Airtunesd] Received and Unknown Update Message May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.051100 PM [AirPlay] ### Command from Client Identifier 'updateMRSupportedCommands' failed: 400/0x190 Bad Request, -6714/0xFFFFE5C6 kNotHandledErr May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.077148 PM [Airtunesd] Received and Unknown Update Message May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.077256 PM [AirPlay] ### Command from Client Identifier 'updateMRSupportedCommands' failed: 400/0x190 Bad Request, -6714/0xFFFFE5C6 kNotHandledErr May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.163191 PM [AirPlay] _requestProcessSetParameterText: dbVolume = -20.0 May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.163315 PM [AirPlay] _requestProcessSetParameterText:server volume set to -20.0 May 22 22:30:04 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:04 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.362704 PM [Airtunesd] Received and Unknown Update Message May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.362832 PM [Airtunesd] Received and Unknown Update Message May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.363029 PM [Airtunesd] Received and Unknown Update Message May 22 22:30:04 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:04.364219 PM [AirPlay] Main audio setting up ALAC/44100/16/2 for default, input no, loopback no, volume:-20.000000 May 22 22:30:04 AxiomAir-f50e2e root[1152]: Source Format ID : 616c6163 May 22 22:30:04 AxiomAir-f50e2e kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 22 22:30:04 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:04:5947] NOTICE: Sending : 2 May 22 22:30:04 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:04:5947] NOTICE: ping May 22 22:30:05 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:05 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:05 AxiomAir-f50e2e root[1152]: Frames: 256, period time: 5804, buff_size: 524288 May 22 22:30:05 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:05.388667 PM [AudioStream] ALSA device is not in SND_PCM_STATE_RUNNING or SND_PCM_STATE_DRAINING state in AudioThreadFn loop. It may be okay. May 22 22:30:05 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:05:3953] NOTICE: Sending : 42["volume", 33] May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: VolumeController::SetAlsaVolume33 May 22 22:30:05 AxiomAir-f50e2e volumio[456]: amixer -M set -c 0 "Digital" 33% May 22 22:30:05 AxiomAir-f50e2e volumio[456]: null May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: VolumeController::Volume 33 May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - stop, service - mpd May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:30:05 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/VERBATIM/IAN/(Sittin' On) The Dock Of The Bay.m4a","favourite":false} May 22 22:30:06 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:06.198273 PM [AirPlay] _requestProcessSetParameterText: dbVolume = -20.0 May 22 22:30:06 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:06.198447 PM [AirPlay] Setting volume to dB=-20.000000, linear=0.100000 May 22 22:30:06 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:06.198505 PM [AirPlay] _requestProcessSetParameterText:server volume set to -20.0 May 22 22:30:06 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:06.245285 PM [AirPlay] Flushing receiver complete: flushSeq 5349 flushTS 2448781121 count 0 May 22 22:30:06 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:06.245422 PM [Airtunesd] Received Play Status Update Message May 22 22:30:06 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:06:2455] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"0"}}] May 22 22:30:06 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:06.246519 PM [AirPlay] ProcessPkt Flush Complete flushSeq 5349 flushTS 2448781121 count 0 Seq # 5374 TS 2448789921 May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: CALLMETHOD: music_service airplay2 Playing [object Object] May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: Airplay stopped playing. May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::servicePushState May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::syncState May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:30:06 AxiomAir-f50e2e volumio[456]: info: Received update from a service different from the one supposed to be playing music. Processing service interruption. Current mpd Received airplay May 22 22:30:06 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:06 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:07 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:07 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:07 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:07.952147 PM [AirPlay] SetRateAndAnchorTime inRate 1 rtpTme 2448786846 netTime (timeline=b827ebfffef50e2e secs=1747953007 flags=0) May 22 22:30:07 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:07.952306 PM [AirPlay] SetRateAndAnchorTime timeline match after #0 tries May 22 22:30:07 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:07.952401 PM [Airtunesd] Received Play Status Update Message May 22 22:30:07 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:07:9525] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"1"}}] May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CALLMETHOD: music_service airplay2 Playing [object Object] May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: Airplay started streaming May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: AIRPLAY: Un-Docking May 22 22:30:07 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:07.976581 PM [AirPlay] Concealed 17 unit gap (118188 vs 118205), curr seq 5349 May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::servicePushState May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::syncState May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:07 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:30:08 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} May 22 22:30:08 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:08.206315 PM [AirPlay] ### Lost packets 5610-5616 (+6, 6 total) May 22 22:30:08 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:08.358821 PM [AirPlay] ### Lost packets 5618-5634 (+16, 22 total) May 22 22:30:08 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:08 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:09 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Authenticated as "vyq0xqyzjs62rkeoe7z1odrno" ! May 22 22:30:09 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Using alsa sink May 22 22:30:09 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Country: "CA" May 22 22:30:09 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Metadata pipe established May 22 22:30:09 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Volume: Sp:49.999237048905165 Volumio: 50 May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} May 22 22:30:09 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:09.169643 PM [Airtunesd] Received Meta Data Update Message May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: VolumeController::SetAlsaVolume32767 May 22 22:30:09 AxiomAir-f50e2e volumio[456]: amixer -M set -c 0 "Digital" 49.999237048905165% May 22 22:30:09 AxiomAir-f50e2e volumio[456]: null May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: VolumeController::Volume 49.999237048905165 May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::pushState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioPushState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: interfaceApi::pushState May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 22 22:30:09 AxiomAir-f50e2e volumio[456]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} May 22 22:30:09 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:09 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:09 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : SessionActive! May 22 22:30:09 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Connect Session is active! May 22 22:30:09 AxiomAir-f50e2e volumio[1541]: ................................................................++++ May 22 22:30:09 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Loading track "Cracklin' Rosie" with Spotify URI "spotify:track:1LjFrPohvGTdXmEz6rHbxi" May 22 22:30:10 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Pushing metadata Vollibrespot: false May 22 22:30:10 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioGetState May 22 22:30:10 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:10 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Currently active: airplay May 22 22:30:10 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Pushing new state :: false May 22 22:30:10 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::servicePushState May 22 22:30:10 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::syncState May 22 22:30:10 AxiomAir-f50e2e volumio[456]: info: CorePlayQueue::getTrack 0 May 22 22:30:10 AxiomAir-f50e2e volumio[456]: info: Received update from a service different from the one supposed to be playing music. Processing service interruption. Current mpd Received volspotconnect2 May 22 22:30:10 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Token: May 22 22:30:10 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:10 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:11 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:11 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:12 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:12.018251 PM [AirPlay] ### Lost packets 6087-6093 (+6, 28 total) May 22 22:30:12 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:12 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:12 AxiomAir-f50e2e volumio[1541]: .....................++++ May 22 22:30:12 AxiomAir-f50e2e volumio[1541]: e is 65537 (0x010001) May 22 22:30:12 AxiomAir-f50e2e volumio[1541]: writing RSA key May 22 22:30:13 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:13 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:14 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:14 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:14 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:14:7026] NOTICE: Sending : 2 May 22 22:30:14 AxiomAir-f50e2e root[1152]: [2025/05/22 22:30:14:7026] NOTICE: ping May 22 22:30:15 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:15 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:15 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Track "Cracklin' Rosie" loaded May 22 22:30:15 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Device is active! May 22 22:30:15 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 22 22:30:15 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 22 22:30:15 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 22 22:30:15 AxiomAir-f50e2e volumio[1339]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioGetState May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:15 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Currently active: airplay May 22 22:30:15 AxiomAir-f50e2e volumio[456]: [SpotifyConnect] Stopping currently active service May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: CoreCommandRouter::volumioStop May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::getState May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: VOLUMIOSTOP: service stopped - airplay May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::stop May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: CoreStateMachine::serviceStop May 22 22:30:15 AxiomAir-f50e2e volumio[456]: info: Received STOP, but no service to execute it May 22 22:30:15 AxiomAir-f50e2e volumio[456]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 22:30:15 AxiomAir-f50e2e volumio[456]: TypeError: Cannot read property 'then' of undefined May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at SpotConnEvents. (/volumio/app/plugins/music_service/volspotconnect2/index.js:148:23) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at emitOne (events.js:116:13) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at SpotConnEvents.emit (events.js:211:7) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at SpotConnEvents.parseData (/volumio/app/plugins/music_service/volspotconnect2/SpotConnController.js:48:16) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at Socket.SpotConnEvents._udpsource.on.msg (/volumio/app/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at emitTwo (events.js:126:13) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at Socket.emit (events.js:214:7) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: at UDP.onMessage [as onmessage] (dgram.js:659:8) May 22 22:30:15 AxiomAir-f50e2e volumio[456]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 22:30:16 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:16.249441 PM [AirPlay] ### Lost packets 6611-6623 (+12, 40 total) May 22 22:30:16 AxiomAir-f50e2e root[1152]: 2025-05-22 10:30:16.325888 PM [AirPlay] ### Lost packets 6629-6631 (+2, 42 total) May 22 22:30:16 AxiomAir-f50e2e lircd[833]: lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:16 AxiomAir-f50e2e lircd-0.9.4c[833]: Error: unable to open '/dev/input/by-id/usb-flirc.tv_flirc-if01-event-kbd' May 22 22:30:16 AxiomAir-f50e2e sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 22:29 May 22 22:30:16 AxiomAir-f50e2e sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 9 (stretch)" NAME="Raspbian GNU/Linux" VERSION_ID="9" VERSION="9 (stretch)" VERSION_CODENAME=stretch 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_FE_VERSION="187be2f9c830c4fe312adf5ee10d6ca76c1221c8" VOLUMIO_BE_VERSION="f0b88cdc7b76371e2de89b66317a3eb412b85d57" VOLUMIO_ARCH="arm" VERSION="V2.13.3" VOLUMIO_VERSION="V2.13.3 - Toasted Cherry" VOLUMIO_VARIANT="AxiomAir" VOLUMIO_HARDWARE="AxiomAir" VOLUMIO_BUILD_DATE="2020-12-30"