-- Logs begin at Sun 2024-09-01 18:00:30 BST, end at Mon 2024-09-02 15:24:45 BST. -- Sep 02 15:23:00 volumio-pi1b volumio[817]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 02 15:23:01 volumio-pi1b volumio[817]: error: Hi Res Audio Failed Login: Missing Login Data Sep 02 15:23:01 volumio-pi1b volumio[817]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 02 15:23:01 volumio-pi1b volumio[817]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 02 15:23:01 volumio-pi1b volumio[817]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 02 15:23:01 volumio-pi1b volumio[817]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 02 15:23:01 volumio-pi1b volumio[817]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 02 15:23:01 volumio-pi1b volumio[817]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Sep 02 15:23:02 volumio-pi1b volumio[817]: info: Stopping AccessToken refresher cron Sep 02 15:23:07 volumio-pi1b systemd[1]: mpd.service: Succeeded. Sep 02 15:23:07 volumio-pi1b systemd[1]: Stopped Music Player Daemon. Sep 02 15:23:07 volumio-pi1b systemd[1]: Starting Music Player Daemon... Sep 02 15:23:08 volumio-pi1b volumio[817]: info: AccessToken refresher cron started Sep 02 15:23:08 volumio-pi1b volumio[817]: info: Stopping AccessToken refresher cron for QOBUZ Sep 02 15:23:08 volumio-pi1b volumio[817]: info: AccessToken refresher cron started for QOBUZ Sep 02 15:23:08 volumio-pi1b volumio[817]: info: Adding TIDAL REST API Endpoints Sep 02 15:23:08 volumio-pi1b volumio[817]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 02 15:23:09 volumio-pi1b volumio[817]: info: Adding QOBUZ REST API Endpoints Sep 02 15:23:09 volumio-pi1b volumio[817]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 02 15:23:10 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:23:10] [connect] Successful connection Sep 02 15:23:10 volumio-pi1b sudo[1412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 15:23:10 volumio-pi1b sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:23:10 volumio-pi1b sudo[1412]: pam_unix(sudo:session): session closed for user root Sep 02 15:23:16 volumio-pi1b volumio[817]: info: MRS: Getting audio outputs on start Sep 02 15:23:16 volumio-pi1b volumio[817]: info: MRS: Requesting all other devices output Sep 02 15:23:16 volumio-pi1b sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 02 15:23:16 volumio-pi1b sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:23:20 volumio-pi1b systemd[1]: Started Volumio Bluetooth Module. Sep 02 15:23:20 volumio-pi1b sudo[1417]: pam_unix(sudo:session): session closed for user root Sep 02 15:23:20 volumio-pi1b volumio[817]: info: Successfully Updated MyVolumio device Sep 02 15:23:22 volumio-pi1b sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 02 15:23:22 volumio-pi1b sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:23:23 volumio-pi1b volumiobt[1419]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 02 15:23:23 volumio-pi1b sudo[1420]: pam_unix(sudo:session): session closed for user root Sep 02 15:23:24 volumio-pi1b sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 02 15:23:25 volumio-pi1b sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 15:23:25 volumio-pi1b volumiobt[1419]: pulseaudio: no process found Sep 02 15:23:25 volumio-pi1b sudo[1424]: pam_unix(sudo:session): session closed for user root Sep 02 15:23:25 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:23:25] [connect] Successful connection Sep 02 15:23:27 volumio-pi1b volumio[817]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio Pi1B' Sep 02 15:23:27 volumio-pi1b volumio[817]: Can't get device info: No such device Sep 02 15:23:27 volumio-pi1b volumio[817]: info: MPD Permissions set Sep 02 15:23:27 volumio-pi1b volumio[817]: info: Remote SSH Started Sep 02 15:23:28 volumio-pi1b dbus-daemon[394]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.11' (uid=1000 pid=1428 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 02 15:23:30 volumio-pi1b systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 02 15:23:30 volumio-pi1b dbus-daemon[394]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 02 15:23:30 volumio-pi1b systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 02 15:23:31 volumio-pi1b rtkit-daemon[1429]: Successfully called chroot. Sep 02 15:23:31 volumio-pi1b rtkit-daemon[1429]: Successfully dropped privileges. Sep 02 15:23:31 volumio-pi1b rtkit-daemon[1429]: Successfully limited resources. Sep 02 15:23:31 volumio-pi1b rtkit-daemon[1429]: Running. Sep 02 15:23:31 volumio-pi1b rtkit-daemon[1429]: Canary thread running. Sep 02 15:23:31 volumio-pi1b rtkit-daemon[1429]: Watchdog thread running. Sep 02 15:23:31 volumio-pi1b dbus-daemon[394]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.12' (uid=0 pid=1429 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 02 15:23:32 volumio-pi1b systemd[1]: Starting Authorization Manager... Sep 02 15:23:34 volumio-pi1b polkitd[1433]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 02 15:23:34 volumio-pi1b dbus-daemon[394]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 02 15:23:34 volumio-pi1b systemd[1]: Started Authorization Manager. Sep 02 15:23:35 volumio-pi1b volumiologrotate[390]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 02 15:23:35 volumio-pi1b volumiologrotate[390]: ls: cannot access 'PI1B': No such file or directory Sep 02 15:23:39 volumio-pi1b dbus-daemon[394]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.32' (uid=1000 pid=1428 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 02 15:23:39 volumio-pi1b systemd[1]: Starting Bluetooth service... Sep 02 15:23:40 volumio-pi1b pulseaudio[1428]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 02 15:23:40 volumio-pi1b pulseaudio[1428]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 02 15:23:41 volumio-pi1b volumiobt[1419]: Applying permissions Sep 02 15:23:41 volumio-pi1b volumiobt[1419]: Setting BT discoverable and pairable Sep 02 15:23:41 volumio-pi1b bluetoothd[1478]: Bluetooth daemon 5.50 Sep 02 15:23:41 volumio-pi1b bluetoothd[1478]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 02 15:23:41 volumio-pi1b dbus-daemon[394]: [system] Successfully activated service 'org.bluez' Sep 02 15:23:41 volumio-pi1b systemd[1]: Started Bluetooth service. Sep 02 15:23:41 volumio-pi1b bluetoothd[1478]: Starting SDP server Sep 02 15:23:41 volumio-pi1b bluetoothd[1478]: Excluding (cli) sap Sep 02 15:23:41 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:23:41] [connect] Successful connection Sep 02 15:23:42 volumio-pi1b kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 02 15:23:42 volumio-pi1b kernel: Bluetooth: BNEP filters: protocol multicast Sep 02 15:23:42 volumio-pi1b kernel: Bluetooth: BNEP socket layer initialized Sep 02 15:23:42 volumio-pi1b bluetoothd[1478]: Bluetooth management interface 1.22 initialized Sep 02 15:23:42 volumio-pi1b dbus-daemon[394]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.33' (uid=0 pid=1478 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# -e power on Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Invalid command in menu main: -e Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Use "help" for a list of available commands in a menu. Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Use "menu " if you want to enter any submenu. Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Use "back" if you want to return to menu main. Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# agent on Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Failed to register agent object Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# discoverable on Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: No default controller available Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# pairable on Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: No default controller available Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# agent NoInputNoOutput Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: Failed to register agent object Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# default-agent Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: No agent is registered Sep 02 15:23:42 volumio-pi1b volumiobt[1419]: [bluetooth]# quit Sep 02 15:23:42 volumio-pi1b pulseaudio[1428]: 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 02 15:23:42 volumio-pi1b volumiobt[1419]: [59B blob data] Sep 02 15:23:44 volumio-pi1b systemd[1]: Starting Hostname Service... Sep 02 15:23:45 volumio-pi1b dbus-daemon[394]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30038ms) Sep 02 15:23:45 volumio-pi1b dbus-daemon[394]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30004ms) Sep 02 15:23:51 volumio-pi1b dbus-daemon[394]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 02 15:23:51 volumio-pi1b systemd[1]: Started Hostname Service. Sep 02 15:23:57 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:23:57] [connect] Successful connection Sep 02 15:24:13 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:24:13] [connect] Successful connection Sep 02 15:24:17 volumio-pi1b volumio[817]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:24:20 volumio-pi1b volumio[817]: Error: write EPIPE Sep 02 15:24:20 volumio-pi1b volumio[817]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Sep 02 15:24:20 volumio-pi1b volumio[817]: at writeGeneric (internal/stream_base_commons.js:147:3) Sep 02 15:24:20 volumio-pi1b volumio[817]: at Socket._writeGeneric (net.js:785:11) Sep 02 15:24:20 volumio-pi1b volumio[817]: at Socket._write (net.js:797:8) Sep 02 15:24:20 volumio-pi1b volumio[817]: at writeOrBuffer (internal/streams/writable.js:358:12) Sep 02 15:24:20 volumio-pi1b volumio[817]: at Socket.Writable.write (internal/streams/writable.js:303:10) Sep 02 15:24:20 volumio-pi1b volumio[817]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:82:16 Sep 02 15:24:20 volumio-pi1b volumio[817]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Sep 02 15:24:20 volumio-pi1b volumio[817]: at Socket.emit (events.js:315:20) Sep 02 15:24:20 volumio-pi1b volumio[817]: at emitReadable_ (internal/streams/readable.js:569:12) Sep 02 15:24:20 volumio-pi1b volumio[817]: at processTicksAndRejections (internal/process/task_queues.js:79:21) { Sep 02 15:24:20 volumio-pi1b volumio[817]: errno: -32, Sep 02 15:24:20 volumio-pi1b volumio[817]: code: 'EPIPE', Sep 02 15:24:20 volumio-pi1b volumio[817]: syscall: 'write' Sep 02 15:24:20 volumio-pi1b volumio[817]: } Sep 02 15:24:20 volumio-pi1b volumio[817]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 15:24:27 volumio-pi1b systemd[1]: systemd-hostnamed.service: Succeeded. Sep 02 15:24:29 volumio-pi1b wpa_supplicant[720]: wlan0: WPA: Group rekeying completed with e0:b9:e5:66:d1:75 [GTK=TKIP] Sep 02 15:24:29 volumio-pi1b wpa_supplicant[720]: wlan0: WPA: Group rekeying completed with e0:b9:e5:66:d1:75 [GTK=TKIP] Sep 02 15:24:30 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:24:30] [connect] Successful connection Sep 02 15:24:41 volumio-pi1b systemd[1]: mpd.service: Start operation timed out. Terminating. Sep 02 15:24:41 volumio-pi1b volumiologrotate[390]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 02 15:24:41 volumio-pi1b volumiologrotate[390]: ls: cannot access 'PI1B': No such file or directory Sep 02 15:24:42 volumio-pi1b systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 15:24:42 volumio-pi1b systemd[1]: mpd.service: Failed with result 'timeout'. Sep 02 15:24:42 volumio-pi1b systemd[1]: Failed to start Music Player Daemon. Sep 02 15:24:43 volumio-pi1b sudo[1388]: pam_unix(sudo:session): session closed for user root Sep 02 15:24:45 volumio-pi1b volumio-remote-updater[413]: [2024-09-02 15:24:45] [connect] Successful connection Sep 02 15:24:45 volumio-pi1b sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 15:23 Sep 02 15:24:45 volumio-pi1b sudo[1540]: 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="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"