-- Logs begin at Wed 2024-04-17 09:00:00 CEST, end at Wed 2024-04-17 09:09:20 CEST. -- Apr 17 09:08:10 volumio1 sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 17 09:08:10 volumio1 sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 09:08:13 volumio1 volumio[727]: info: MRS: Getting audio outputs on start Apr 17 09:08:14 volumio1 volumio[727]: info: MRS: Requesting all other devices output Apr 17 09:08:14 volumio1 systemd[1]: Started Volumio Bluetooth Module. Apr 17 09:08:15 volumio1 sudo[1240]: pam_unix(sudo:session): session closed for user root Apr 17 09:08:16 volumio1 sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 17 09:08:16 volumio1 sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 09:08:17 volumio1 volumiobt[1242]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 17 09:08:17 volumio1 sudo[1243]: pam_unix(sudo:session): session closed for user root Apr 17 09:08:19 volumio1 sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 17 09:08:19 volumio1 sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 09:08:20 volumio1 volumiobt[1242]: pulseaudio: no process found Apr 17 09:08:20 volumio1 sudo[1247]: pam_unix(sudo:session): session closed for user root Apr 17 09:08:24 volumio1 dbus-daemon[325]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.12' (uid=1000 pid=1284 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 17 09:08:25 volumio1 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 17 09:08:26 volumio1 dbus-daemon[325]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 17 09:08:26 volumio1 systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 17 09:08:26 volumio1 rtkit-daemon[1289]: Successfully called chroot. Apr 17 09:08:26 volumio1 rtkit-daemon[1289]: Successfully dropped privileges. Apr 17 09:08:26 volumio1 rtkit-daemon[1289]: Successfully limited resources. Apr 17 09:08:26 volumio1 rtkit-daemon[1289]: Running. Apr 17 09:08:26 volumio1 rtkit-daemon[1289]: Watchdog thread running. Apr 17 09:08:26 volumio1 rtkit-daemon[1289]: Canary thread running. Apr 17 09:08:26 volumio1 dbus-daemon[325]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.13' (uid=0 pid=1289 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 17 09:08:26 volumio1 systemd[1]: Starting Authorization Manager... Apr 17 09:08:30 volumio1 polkitd[1292]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 17 09:08:30 volumio1 dbus-daemon[325]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 17 09:08:30 volumio1 systemd[1]: Started Authorization Manager. Apr 17 09:08:39 volumio1 dbus-daemon[325]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.33' (uid=1000 pid=1284 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 17 09:08:40 volumio1 dbus-daemon[325]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30051ms) Apr 17 09:08:41 volumio1 dbus-daemon[325]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30004ms) Apr 17 09:08:41 volumio1 pulseaudio[1284]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 17 09:08:41 volumio1 pulseaudio[1284]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 17 09:08:42 volumio1 volumiobt[1242]: Applying permissions Apr 17 09:08:42 volumio1 volumiobt[1242]: Setting BT discoverable and pairable Apr 17 09:08:43 volumio1 systemd[1]: Starting Bluetooth service... Apr 17 09:08:44 volumio1 bluetoothd[1301]: Bluetooth daemon 5.50 Apr 17 09:08:44 volumio1 bluetoothd[1301]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 17 09:08:44 volumio1 dbus-daemon[325]: [system] Successfully activated service 'org.bluez' Apr 17 09:08:44 volumio1 systemd[1]: Started Bluetooth service. Apr 17 09:08:44 volumio1 bluetoothd[1301]: Starting SDP server Apr 17 09:08:44 volumio1 bluetoothd[1301]: Excluding (cli) sap Apr 17 09:08:46 volumio1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 17 09:08:46 volumio1 kernel: Bluetooth: BNEP filters: protocol multicast Apr 17 09:08:46 volumio1 kernel: Bluetooth: BNEP socket layer initialized Apr 17 09:08:46 volumio1 bluetoothd[1301]: Bluetooth management interface 1.22 initialized Apr 17 09:08:46 volumio1 dbus-daemon[325]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.35' (uid=0 pid=1301 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 17 09:08:46 volumio1 volumiobt[1242]: [bluetooth]# -e power on Apr 17 09:08:46 volumio1 volumiobt[1242]: Invalid command in menu main: -e Apr 17 09:08:46 volumio1 volumiobt[1242]: Apr 17 09:08:46 volumio1 volumiobt[1242]: Use "help" for a list of available commands in a menu. Apr 17 09:08:46 volumio1 volumiobt[1242]: Use "menu " if you want to enter any submenu. Apr 17 09:08:46 volumio1 volumiobt[1242]: Use "back" if you want to return to menu main. Apr 17 09:08:46 volumio1 volumiobt[1242]: [bluetooth]# agent on Apr 17 09:08:46 volumio1 volumiobt[1242]: Failed to register agent object Apr 17 09:08:46 volumio1 volumiobt[1242]: [bluetooth]# discoverable on Apr 17 09:08:46 volumio1 volumiobt[1242]: No default controller available Apr 17 09:08:46 volumio1 volumiobt[1242]: [bluetooth]# pairable on Apr 17 09:08:46 volumio1 volumiobt[1242]: No default controller available Apr 17 09:08:46 volumio1 volumiobt[1242]: [bluetooth]# agent NoInputNoOutput Apr 17 09:08:46 volumio1 volumiobt[1242]: Failed to register agent object Apr 17 09:08:47 volumio1 volumiobt[1242]: [bluetooth]# default-agent Apr 17 09:08:47 volumio1 volumiobt[1242]: No agent is registered Apr 17 09:08:47 volumio1 volumiobt[1242]: [bluetooth]# quit Apr 17 09:08:47 volumio1 pulseaudio[1284]: 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 Apr 17 09:08:47 volumio1 volumiobt[1242]: [59B blob data] Apr 17 09:08:48 volumio1 systemd[1]: Starting Hostname Service... Apr 17 09:08:56 volumio1 dbus-daemon[325]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 17 09:08:56 volumio1 systemd[1]: Started Hostname Service. Apr 17 09:08:59 volumio1 volumio[727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 09:09:04 volumio1 volumio[727]: Error: write EPIPE Apr 17 09:09:04 volumio1 volumio[727]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Apr 17 09:09:04 volumio1 volumio[727]: at writeGeneric (internal/stream_base_commons.js:147:3) Apr 17 09:09:04 volumio1 volumio[727]: at Socket._writeGeneric (net.js:785:11) Apr 17 09:09:04 volumio1 volumio[727]: at Socket.connect (net.js:767:12) Apr 17 09:09:04 volumio1 volumio[727]: at Object.onceWrapper (events.js:421:28) Apr 17 09:09:04 volumio1 volumio[727]: at Socket.emit (events.js:327:22) Apr 17 09:09:04 volumio1 volumio[727]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1132:10) { Apr 17 09:09:04 volumio1 volumio[727]: errno: -32, Apr 17 09:09:04 volumio1 volumio[727]: code: 'EPIPE', Apr 17 09:09:04 volumio1 volumio[727]: syscall: 'write' Apr 17 09:09:04 volumio1 volumio[727]: } Apr 17 09:09:04 volumio1 volumio[727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 09:09:04 volumio1 mpd[1232]: Apr 17 09:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 09:09:05 volumio1 systemd[1]: Started Music Player Daemon. Apr 17 09:09:07 volumio1 sudo[1171]: pam_unix(sudo:session): session closed for user root Apr 17 09:09:20 volumio1 sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-17 09:08 Apr 17 09:09:20 volumio1 sudo[1319]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"