-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2025-02-21 12:57:26 UTC. -- Feb 21 12:56:11 volumio systemd[1]: Reexecuting. Feb 21 12:56:11 volumio kernel: printk: systemd: 40 output lines suppressed due to ratelimiting Feb 21 12:56:11 volumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 21 12:56:11 volumio systemd[1]: Detected architecture arm. Feb 21 12:56:11 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:12 volumio systemd[1]: systemd-journal-flush.service: Succeeded. Feb 21 12:56:12 volumio systemd[1]: Stopped Flush Journal to Persistent Storage. Feb 21 12:56:12 volumio systemd[1]: Stopping Flush Journal to Persistent Storage... Feb 21 12:56:12 volumio systemd-journald[194]: Journal stopped Feb 21 12:56:12 volumio systemd-journald[194]: Received SIGTERM from PID 1 (systemd). Feb 21 12:56:12 volumio systemd[1]: Stopping Journal Service... Feb 21 12:56:12 volumio systemd[1]: systemd-journald.service: Succeeded. Feb 21 12:56:12 volumio systemd[1]: Stopped Journal Service. Feb 21 12:56:12 volumio systemd[1]: Starting Journal Service... Feb 21 12:56:12 volumio systemd-journald[9957]: Journal started Feb 21 12:56:12 volumio systemd-journald[9957]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 15.0M, max 30.0M, 15.0M free. Feb 21 12:56:12 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:12 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 12:56:12 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:12 volumio systemd[1]: Started Journal Service. Feb 21 12:56:12 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Feb 21 12:56:12 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:12 volumio systemd[1]: Started Flush Journal to Persistent Storage. Feb 21 12:56:13 volumio dbus-daemon[520]: [system] Reloaded configuration Feb 21 12:56:14 volumio systemd[1]: Reloading. Feb 21 12:56:14 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:15 volumio systemd[1]: Stopping WPA supplicant... Feb 21 12:56:15 volumio systemd[1]: wpa_supplicant.service: Succeeded. Feb 21 12:56:15 volumio systemd[1]: Stopped WPA supplicant. Feb 21 12:56:15 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:15 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 12:56:15 volumio systemd[1]: Starting WPA supplicant... Feb 21 12:56:15 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:15 volumio systemd[1]: Started WPA supplicant. Feb 21 12:56:15 volumio wpa_supplicant[10033]: Successfully initialized wpa_supplicant Feb 21 12:56:15 volumio dbus-daemon[520]: [system] Reloaded configuration Feb 21 12:56:15 volumio systemd[1]: Reloading. Feb 21 12:56:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:16 volumio systemd[1]: Reloading D-Bus System Message Bus. Feb 21 12:56:16 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:16 volumio dbus-daemon[520]: [system] Reloaded configuration Feb 21 12:56:16 volumio dbus-send[10097]: method return time=1740142576.693705 sender=org.freedesktop.DBus -> destination=:1.62 serial=3 reply_serial=2 Feb 21 12:56:16 volumio systemd[1]: Reloaded D-Bus System Message Bus. Feb 21 12:56:17 volumio systemd[1]: Reloading. Feb 21 12:56:17 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:18 volumio systemd[1]: Reloading. Feb 21 12:56:18 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:19 volumio systemd[1]: Stopping Bluetooth service... Feb 21 12:56:19 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:25 volumio bluetoothd[687]: Terminating Feb 21 12:56:25 volumio bluetoothd[687]: Endpoint unregistered: sender=:1.45 path=/MediaEndpoint/A2DPSource Feb 21 12:56:25 volumio bluetoothd[687]: Endpoint unregistered: sender=:1.45 path=/MediaEndpoint/A2DPSink Feb 21 12:56:25 volumio dbus-daemon[520]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.45" (uid=1000 pid=1326 comm="/usr/bin/pulseaudio --high-priority=true ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=687 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 21 12:56:25 volumio dbus-daemon[520]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.45" (uid=1000 pid=1326 comm="/usr/bin/pulseaudio --high-priority=true ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=687 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 21 12:56:25 volumio bluetoothd[687]: Stopping SDP server Feb 21 12:56:25 volumio bluetoothd[687]: Exit Feb 21 12:56:25 volumio dbus-daemon[520]: [system] Rejected send message, 1 matched rules; type="method_return", sender=":1.47" (uid=1000 pid=1357 comm="/usr/bin/python /bin/bt/simple-agent -c NoInputNoO") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.5" (uid=0 pid=687 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 21 12:56:25 volumio systemd[1]: bluetooth.service: Succeeded. Feb 21 12:56:25 volumio systemd[1]: Stopped Bluetooth service. Feb 21 12:56:25 volumio systemd[1]: bthelper@hci0.service: Succeeded. Feb 21 12:56:25 volumio systemd[1]: Stopped Raspberry Pi bluetooth helper. Feb 21 12:56:25 volumio systemd[1]: Stopping Raspberry Pi bluetooth helper... Feb 21 12:56:25 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 12:56:25 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 21 12:56:25 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:25 volumio bthelper[10141]: Raspberry Pi BDADDR already set Feb 21 12:56:25 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Feb 21 12:56:25 volumio systemd[1]: Starting Bluetooth service... Feb 21 12:56:25 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:25 volumio bluetoothd[10151]: Bluetooth daemon 5.50 Feb 21 12:56:25 volumio bluetoothd[10151]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 21 12:56:25 volumio systemd[1]: Started Bluetooth service. Feb 21 12:56:25 volumio bluetoothd[10151]: Starting SDP server Feb 21 12:56:25 volumio bluetoothd[10151]: Bluetooth management interface 1.18 initialized Feb 21 12:56:25 volumio dbus-daemon[520]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.63' (uid=0 pid=10151 comm="/usr/lib/bluetooth/bluetoothd ") Feb 21 12:56:27 volumio systemd[1]: Starting Hostname Service... Feb 21 12:56:27 volumio bluetoothd[10151]: Sap driver initialization failed. Feb 21 12:56:27 volumio bluetoothd[10151]: sap-server: Operation not permitted (1) Feb 21 12:56:27 volumio bluetoothd[10151]: Failed to set privacy: Rejected (0x0b) Feb 21 12:56:27 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:56:27 volumio bluetoothd[10151]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSource Feb 21 12:56:27 volumio bluetoothd[10151]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSink Feb 21 12:56:27 volumio pulseaudio[1326]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Feb 21 12:56:28 volumio dbus-daemon[520]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 21 12:56:28 volumio systemd[1]: Started Hostname Service. Feb 21 12:56:31 volumio bthelper[10141]: Changing power off succeeded Feb 21 12:56:31 volumio bthelper[10141]: [CHG] Controller B8:27:EB:96:27:25 Class: 0x000c041c Feb 21 12:56:31 volumio bthelper[10141]: Changing power on succeeded Feb 21 12:56:57 volumio systemd[1]: Reloading. Feb 21 12:56:58 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:58 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Feb 21 12:56:59 volumio systemd[1]: Reloading. Feb 21 12:56:59 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:56:59 volumio systemd[1]: Reloading. Feb 21 12:57:00 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:57:00 volumio sshd[5683]: Received signal 15; terminating. Feb 21 12:57:00 volumio systemd[1]: Stopping OpenBSD Secure Shell server... Feb 21 12:57:00 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:57:09 volumio systemd[1]: ssh.service: Succeeded. Feb 21 12:57:09 volumio systemd[1]: Stopped OpenBSD Secure Shell server. Feb 21 12:57:09 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 12:57:09 volumio systemd[1]: Starting OpenBSD Secure Shell server... Feb 21 12:57:09 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:57:15 volumio sshd[10416]: Server listening on 0.0.0.0 port 22. Feb 21 12:57:15 volumio sshd[10416]: Server listening on :: port 22. Feb 21 12:57:15 volumio systemd[1]: Started OpenBSD Secure Shell server. Feb 21 12:57:17 volumio avahi-daemon[527]: Files changed, reloading. Feb 21 12:57:17 volumio avahi-daemon[527]: No service file found in /etc/avahi/services. Feb 21 12:57:17 volumio avahi-daemon[527]: Files changed, reloading. Feb 21 12:57:17 volumio avahi-daemon[527]: No service file found in /etc/avahi/services. Feb 21 12:57:19 volumio dbus-daemon[520]: [system] Reloaded configuration Feb 21 12:57:19 volumio systemd[1]: Reloading. Feb 21 12:57:20 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:57:20 volumio systemd[1]: Reloading D-Bus System Message Bus. Feb 21 12:57:20 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:57:20 volumio dbus-daemon[520]: [system] Reloaded configuration Feb 21 12:57:20 volumio dbus-send[10444]: method return time=1740142640.962772 sender=org.freedesktop.DBus -> destination=:1.67 serial=3 reply_serial=2 Feb 21 12:57:20 volumio systemd[1]: Reloaded D-Bus System Message Bus. Feb 21 12:57:22 volumio systemd[1]: Reloading. Feb 21 12:57:23 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:57:23 volumio systemd[1]: Reloading. Feb 21 12:57:24 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 21 12:57:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 21 12:57:24 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:57:24 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 21 12:57:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 21 12:57:24 volumio avahi-daemon[527]: Got SIGTERM, quitting. Feb 21 12:57:24 volumio systemd[1]: Stopping Avahi mDNS/DNS-SD Stack... Feb 21 12:57:24 volumio avahi-daemon[527]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100. Feb 21 12:57:24 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 21 12:57:24 volumio vtcs[1322]: [2025-02-21 12:57:24.749] [tisoc] [error] [avahiImpl.cpp:113] avahiClientCallback() AVAHI_CLIENT_S_COLLISION/AVAHI_CLIENT_FAILURE Feb 21 12:57:24 volumio volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 21 12:57:24 volumio dbus-daemon[520]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.68' (uid=0 pid=854 comm="/usr/sbin/smbd --foreground --no-process-group ") Feb 21 12:57:24 volumio volumio[838]: error: Discovery: Advertisement error: Error: dns service error: unknown Feb 21 12:57:24 volumio volumio[838]: error: Discovery: advertisement error: Error: dns service error: unknown Feb 21 12:57:24 volumio volumio[838]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Feb 21 12:57:24 volumio volumio[838]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 21 12:57:24 volumio volumio[838]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 12:57:24 volumio volumio[838]: Error: dns service error: unknown Feb 21 12:57:24 volumio volumio[838]: at Browser.on_service_changed (/volumio/node_modules/mdns/lib/browser.js:84:33) Feb 21 12:57:24 volumio volumio[838]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 21 12:57:24 volumio volumio[838]: errorCode: -65537 Feb 21 12:57:24 volumio volumio[838]: } Feb 21 12:57:24 volumio volumio[838]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 12:57:25 volumio avahi-daemon[527]: avahi-daemon 0.7 exiting. Feb 21 12:57:25 volumio systemd[1]: avahi-daemon.service: Succeeded. Feb 21 12:57:25 volumio systemd[1]: Stopped Avahi mDNS/DNS-SD Stack. Feb 21 12:57:25 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 21 12:57:25 volumio avahi-daemon[10504]: Process 527 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Feb 21 12:57:25 volumio avahi-daemon[10504]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 21 12:57:25 volumio avahi-daemon[10504]: Successfully dropped root privileges. Feb 21 12:57:25 volumio avahi-daemon[10504]: avahi-daemon 0.7 starting up. Feb 21 12:57:25 volumio dbus-daemon[520]: [system] Successfully activated service 'org.freedesktop.Avahi' Feb 21 12:57:25 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 21 12:57:25 volumio avahi-daemon[10504]: Successfully called chroot(). Feb 21 12:57:25 volumio avahi-daemon[10504]: Successfully dropped remaining capabilities. Feb 21 12:57:25 volumio avahi-daemon[10504]: No service file found in /etc/avahi/services. Feb 21 12:57:25 volumio avahi-daemon[10504]: *** WARNING: Detected another IPv4 mDNS stack running on this host. This makes mDNS unreliable and is thus not recommended. *** Feb 21 12:57:25 volumio avahi-daemon[10504]: *** WARNING: Detected another IPv6 mDNS stack running on this host. This makes mDNS unreliable and is thus not recommended. *** Feb 21 12:57:25 volumio avahi-daemon[10504]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.100. Feb 21 12:57:25 volumio avahi-daemon[10504]: New relevant interface wlan0.IPv4 for mDNS. Feb 21 12:57:25 volumio avahi-daemon[10504]: Network interface enumeration completed. Feb 21 12:57:25 volumio avahi-daemon[10504]: Registering new address record for 192.168.1.100 on wlan0.IPv4. Feb 21 12:57:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 21 12:57:26 volumio sudo[10512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-21 12:56 Feb 21 12:57:26 volumio sudo[10512]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"