-- Logs begin at Sat 2024-08-31 23:00:50 BST, end at Sun 2024-09-01 09:58:08 BST. -- Sep 01 09:57:00 volumio-pi1b volumio[816]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 01 09:57:01 volumio-pi1b volumio[816]: error: Hi Res Audio Failed Login: Missing Login Data Sep 01 09:57:01 volumio-pi1b volumio[816]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 01 09:57:01 volumio-pi1b volumio[816]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 01 09:57:01 volumio-pi1b volumio[816]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 01 09:57:01 volumio-pi1b volumio[816]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 01 09:57:01 volumio-pi1b volumio[816]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 01 09:57:01 volumio-pi1b volumio[816]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Sep 01 09:57:02 volumio-pi1b volumio[816]: info: Stopping AccessToken refresher cron Sep 01 09:57:05 volumio-pi1b volumio[816]: info: AccessToken refresher cron started Sep 01 09:57:05 volumio-pi1b volumio-remote-updater[412]: [2024-09-01 09:57:05] [connect] Successful connection Sep 01 09:57:05 volumio-pi1b volumio[816]: info: Stopping AccessToken refresher cron for QOBUZ Sep 01 09:57:05 volumio-pi1b systemd[1]: mpd.service: Succeeded. Sep 01 09:57:05 volumio-pi1b systemd[1]: Stopped Music Player Daemon. Sep 01 09:57:06 volumio-pi1b systemd[1]: Starting Music Player Daemon... Sep 01 09:57:06 volumio-pi1b volumio[816]: info: AccessToken refresher cron started for QOBUZ Sep 01 09:57:06 volumio-pi1b volumio[816]: info: Adding TIDAL REST API Endpoints Sep 01 09:57:06 volumio-pi1b volumio[816]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 01 09:57:06 volumio-pi1b volumio[816]: info: Adding QOBUZ REST API Endpoints Sep 01 09:57:06 volumio-pi1b volumio[816]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 01 09:57:08 volumio-pi1b sudo[1369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 09:57:08 volumio-pi1b sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 09:57:08 volumio-pi1b sudo[1369]: pam_unix(sudo:session): session closed for user root Sep 01 09:57:09 volumio-pi1b sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 01 09:57:09 volumio-pi1b sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 09:57:09 volumio-pi1b volumio[816]: info: MRS: Getting audio outputs on start Sep 01 09:57:09 volumio-pi1b volumio[816]: info: MRS: Requesting all other devices output Sep 01 09:57:10 volumio-pi1b systemd[1]: Started Volumio Bluetooth Module. Sep 01 09:57:10 volumio-pi1b sudo[1373]: pam_unix(sudo:session): session closed for user root Sep 01 09:57:12 volumio-pi1b volumio[816]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio Pi1B' Sep 01 09:57:12 volumio-pi1b volumio[816]: Can't get device info: No such device Sep 01 09:57:12 volumio-pi1b volumio[816]: info: MPD Permissions set Sep 01 09:57:12 volumio-pi1b sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 01 09:57:12 volumio-pi1b sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 09:57:12 volumio-pi1b volumiobt[1375]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 01 09:57:12 volumio-pi1b sudo[1376]: pam_unix(sudo:session): session closed for user root Sep 01 09:57:13 volumio-pi1b volumio[816]: info: Remote SSH Started Sep 01 09:57:14 volumio-pi1b sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 01 09:57:14 volumio-pi1b sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 09:57:14 volumio-pi1b volumiobt[1375]: pulseaudio: no process found Sep 01 09:57:14 volumio-pi1b sudo[1379]: pam_unix(sudo:session): session closed for user root Sep 01 09:57:17 volumio-pi1b dbus-daemon[397]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.11' (uid=1000 pid=1384 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 01 09:57:19 volumio-pi1b systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 01 09:57:20 volumio-pi1b dbus-daemon[397]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 01 09:57:20 volumio-pi1b systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 01 09:57:20 volumio-pi1b rtkit-daemon[1385]: Successfully called chroot. Sep 01 09:57:20 volumio-pi1b rtkit-daemon[1385]: Successfully dropped privileges. Sep 01 09:57:20 volumio-pi1b rtkit-daemon[1385]: Successfully limited resources. Sep 01 09:57:20 volumio-pi1b rtkit-daemon[1385]: Running. Sep 01 09:57:20 volumio-pi1b rtkit-daemon[1385]: Watchdog thread running. Sep 01 09:57:20 volumio-pi1b rtkit-daemon[1385]: Canary thread running. Sep 01 09:57:20 volumio-pi1b dbus-daemon[397]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.12' (uid=0 pid=1385 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 01 09:57:20 volumio-pi1b systemd[1]: Starting Authorization Manager... Sep 01 09:57:21 volumio-pi1b volumio-remote-updater[412]: [2024-09-01 09:57:21] [connect] Successful connection Sep 01 09:57:23 volumio-pi1b polkitd[1388]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 01 09:57:23 volumio-pi1b dbus-daemon[397]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 01 09:57:23 volumio-pi1b systemd[1]: Started Authorization Manager. Sep 01 09:57:28 volumio-pi1b dbus-daemon[397]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.32' (uid=1000 pid=1384 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 01 09:57:28 volumio-pi1b systemd[1]: Starting Bluetooth service... Sep 01 09:57:29 volumio-pi1b pulseaudio[1384]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 01 09:57:29 volumio-pi1b pulseaudio[1384]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 01 09:57:30 volumio-pi1b volumiobt[1375]: Applying permissions Sep 01 09:57:30 volumio-pi1b volumiobt[1375]: Setting BT discoverable and pairable Sep 01 09:57:30 volumio-pi1b bluetoothd[1393]: Bluetooth daemon 5.50 Sep 01 09:57:30 volumio-pi1b bluetoothd[1393]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 01 09:57:30 volumio-pi1b dbus-daemon[397]: [system] Successfully activated service 'org.bluez' Sep 01 09:57:31 volumio-pi1b systemd[1]: Started Bluetooth service. Sep 01 09:57:31 volumio-pi1b bluetoothd[1393]: Starting SDP server Sep 01 09:57:31 volumio-pi1b bluetoothd[1393]: Excluding (cli) sap Sep 01 09:57:32 volumio-pi1b kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 01 09:57:32 volumio-pi1b kernel: Bluetooth: BNEP filters: protocol multicast Sep 01 09:57:32 volumio-pi1b kernel: Bluetooth: BNEP socket layer initialized Sep 01 09:57:32 volumio-pi1b bluetoothd[1393]: Bluetooth management interface 1.22 initialized Sep 01 09:57:32 volumio-pi1b dbus-daemon[397]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.33' (uid=0 pid=1393 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# -e power on Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Invalid command in menu main: -e Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Use "help" for a list of available commands in a menu. Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Use "menu " if you want to enter any submenu. Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Use "back" if you want to return to menu main. Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# agent on Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Failed to register agent object Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# discoverable on Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: No default controller available Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# pairable on Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: No default controller available Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# agent NoInputNoOutput Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: Failed to register agent object Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# default-agent Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: No agent is registered Sep 01 09:57:32 volumio-pi1b volumiobt[1375]: [bluetooth]# quit Sep 01 09:57:32 volumio-pi1b pulseaudio[1384]: 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 Sep 01 09:57:33 volumio-pi1b volumiobt[1375]: [59B blob data] Sep 01 09:57:34 volumio-pi1b systemd[1]: Starting Hostname Service... Sep 01 09:57:37 volumio-pi1b volumio-remote-updater[412]: [2024-09-01 09:57:37] [connect] Successful connection Sep 01 09:57:39 volumio-pi1b dbus-daemon[397]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30025ms) Sep 01 09:57:39 volumio-pi1b dbus-daemon[397]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30016ms) Sep 01 09:57:41 volumio-pi1b dbus-daemon[397]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 01 09:57:41 volumio-pi1b systemd[1]: Started Hostname Service. Sep 01 09:57:43 volumio-pi1b volumiologrotate[408]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 01 09:57:43 volumio-pi1b volumiologrotate[408]: ls: cannot access 'PI1B': No such file or directory Sep 01 09:57:50 volumio-pi1b volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 09:57:51 volumio-pi1b volumio[816]: Error: write EPIPE Sep 01 09:57:51 volumio-pi1b volumio[816]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Sep 01 09:57:51 volumio-pi1b volumio[816]: at writeGeneric (internal/stream_base_commons.js:147:3) Sep 01 09:57:51 volumio-pi1b volumio[816]: at Socket._writeGeneric (net.js:785:11) Sep 01 09:57:51 volumio-pi1b volumio[816]: at Socket._write (net.js:797:8) Sep 01 09:57:51 volumio-pi1b volumio[816]: at writeOrBuffer (internal/streams/writable.js:358:12) Sep 01 09:57:51 volumio-pi1b volumio[816]: at Socket.Writable.write (internal/streams/writable.js:303:10) Sep 01 09:57:51 volumio-pi1b volumio[816]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:82:16 Sep 01 09:57:51 volumio-pi1b volumio[816]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Sep 01 09:57:51 volumio-pi1b volumio[816]: at Socket.emit (events.js:315:20) Sep 01 09:57:51 volumio-pi1b volumio[816]: at emitReadable_ (internal/streams/readable.js:569:12) Sep 01 09:57:51 volumio-pi1b volumio[816]: at processTicksAndRejections (internal/process/task_queues.js:79:21) { Sep 01 09:57:51 volumio-pi1b volumio[816]: errno: -32, Sep 01 09:57:51 volumio-pi1b volumio[816]: code: 'EPIPE', Sep 01 09:57:51 volumio-pi1b volumio[816]: syscall: 'write' Sep 01 09:57:51 volumio-pi1b volumio[816]: } Sep 01 09:57:51 volumio-pi1b volumio[816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 09:57:52 volumio-pi1b volumio-remote-updater[412]: [2024-09-01 09:57:52] [connect] Successful connection Sep 01 09:58:07 volumio-pi1b sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 09:57 Sep 01 09:58:07 volumio-pi1b sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 09:58:08 volumio-pi1b volumio-remote-updater[412]: [2024-09-01 09:58:08] [connect] Successful connection 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"