Jul 01 17:18:34 pi5dev ntpd[1170]: CLOCK: time stepped by 15057.204977 Jul 01 17:18:34 pi5dev ntpd[1170]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 01 17:18:34 pi5dev volumio[1357]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jul 01 17:18:34 pi5dev volumio[1357]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 01 17:18:34 pi5dev volumio[1357]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 01 17:18:34 pi5dev sudo[1904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 01 17:18:34 pi5dev sudo[1904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 01 17:18:34 pi5dev volumio[1357]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 01 17:18:34 pi5dev volumio[1357]: info: Stopping AccessToken refresher cron Jul 01 17:18:34 pi5dev sudo[1910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 01 17:18:34 pi5dev sudo[1910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 01 17:18:34 pi5dev volumio[1357]: info: AccessToken refresher cron started Jul 01 17:18:34 pi5dev volumio[1357]: info: Stopping AccessToken refresher cron for QOBUZ Jul 01 17:18:34 pi5dev volumio[1357]: info: AccessToken refresher cron started for QOBUZ Jul 01 17:18:34 pi5dev volumio[1357]: info: Adding TIDAL REST API Endpoints Jul 01 17:18:34 pi5dev volumio[1357]: info: Adding QOBUZ REST API Endpoints Jul 01 17:18:34 pi5dev volumio[1357]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jul 01 17:18:34 pi5dev volumio[1357]: info: MPD Permissions set Jul 01 17:18:34 pi5dev volumio[1357]: info: Setting Geolocation for MyVolumio to au1 Jul 01 17:18:34 pi5dev volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 17:18:34 pi5dev volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 17:18:34 pi5dev volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 17:18:34 pi5dev volumio[1357]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Jul 01 17:18:34 pi5dev volumio[1357]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 17:18:34 pi5dev sudo[1904]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev sudo[1910]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev volumio[1357]: Error: Unable to resolve or reject the same promise twice Jul 01 17:18:34 pi5dev volumio[1357]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 01 17:18:34 pi5dev volumio[1357]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jul 01 17:18:34 pi5dev volumio[1357]: at Socket.emit (node:events:514:28) Jul 01 17:18:34 pi5dev volumio[1357]: at addChunk (node:internal/streams/readable:343:12) Jul 01 17:18:34 pi5dev volumio[1357]: at readableAddChunk (node:internal/streams/readable:316:9) Jul 01 17:18:34 pi5dev volumio[1357]: at Readable.push (node:internal/streams/readable:253:10) Jul 01 17:18:34 pi5dev volumio[1357]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jul 01 17:18:34 pi5dev volumio[1357]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 17:18:34 pi5dev sudo[1914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 01 17:18:34 pi5dev sudo[1914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 01 17:18:34 pi5dev sudo[1917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 01 17:18:34 pi5dev sudo[1917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 01 17:18:34 pi5dev systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jul 01 17:18:34 pi5dev systemd[1]: mpd.service: Deactivated successfully. Jul 01 17:18:34 pi5dev systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 01 17:18:34 pi5dev systemd[1]: mpd.service: Consumed 1.206s CPU time. Jul 01 17:18:34 pi5dev volumiobt[1933]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jul 01 17:18:34 pi5dev systemd[1]: mpd.socket: Deactivated successfully. Jul 01 17:18:34 pi5dev sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jul 01 17:18:34 pi5dev systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 01 17:18:34 pi5dev sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 01 17:18:34 pi5dev systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 01 17:18:34 pi5dev systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 01 17:18:34 pi5dev sudo[1934]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev systemd[1]: Starting mpd.service - Music Player Daemon... Jul 01 17:18:34 pi5dev sudo[1938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jul 01 17:18:34 pi5dev sudo[1938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 01 17:18:34 pi5dev sudo[1938]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev sudo[1914]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jul 01 17:18:34 pi5dev volumiobt[1941]: INFO [BTSTART] Powering on Bluetooth if needed... Jul 01 17:18:34 pi5dev sudo[1917]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev bluetoothd[923]: Adv Monitor app :1.23 disconnected from D-Bus Jul 01 17:18:34 pi5dev volumiobt[1945]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jul 01 17:18:34 pi5dev sudo[1937]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 01 17:18:34 pi5dev sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 01 17:18:34 pi5dev sudo[1937]: pam_unix(sudo:session): session closed for user root Jul 01 17:18:34 pi5dev volumiobt[1947]: [75B blob data] Jul 01 17:18:34 pi5dev volumiobt[1947]: [bluetooth]# pairable on Jul 01 17:18:34 pi5dev bluetoothd[923]: Adv Monitor app :1.24 disconnected from D-Bus Jul 01 17:18:34 pi5dev volumiobt[1947]: [bluetooth]# Jul 01 17:18:34 pi5dev volumiobt[1949]: INFO [BTSTART] Registering Bluetooth agent... Jul 01 17:18:34 pi5dev bluetoothd[923]: Adv Monitor app :1.25 disconnected from D-Bus Jul 01 17:18:34 pi5dev volumiobt[1951]: No agent is registered Jul 01 17:18:34 pi5dev bluetoothd[923]: Adv Monitor app :1.26 disconnected from D-Bus Jul 01 17:18:34 pi5dev volumiobt[1952]: INFO [BTSTART] Agent registered successfully. Jul 01 17:18:34 pi5dev volumiobt[1953]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jul 01 17:18:34 pi5dev sudo[1957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-01 17:17' Jul 01 17:18:34 pi5dev sudo[1957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="f9454895cc8e2f1971c705a815a7226e021e37d7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 28 16:48:46 UTC 2025" VOLUMIO_VERSION="4.014" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1b557e4869cd2eea14f4113140ecea1c"