-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2025-06-30 14:26:00 CEST. -- Jun 30 14:25:04 volumio volumio[1311]: info: CALLMETHOD: audio_interface bluetooth_controller connectBluetoothDevice [object Object] Jun 30 14:25:04 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , connectBluetoothDevice Jun 30 14:25:04 volumio volumio[1311]: info: Connecting bluetooth device: 90:F2:60:0E:3C:8F Jun 30 14:25:04 volumio volumio[1311]: info: AVRCP: adding device: 90:F2:60:0E:3C:8F Jun 30 14:25:04 volumio volumio[1311]: info: known: { Jun 30 14:25:04 volumio volumio[1311]: "90:F2:60:0E:3C:8F": { Jun 30 14:25:04 volumio volumio[1311]: "virtualPath": "", Jun 30 14:25:04 volumio volumio[1311]: "devicePath": "", Jun 30 14:25:04 volumio volumio[1311]: "inputEvent": null, Jun 30 14:25:04 volumio volumio[1311]: "keyboard": null Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: info: known: true Jun 30 14:25:04 volumio volumio[1311]: info: Starting bluetooth device scan Jun 30 14:25:04 volumio bluetoothd[792]: a2dp-sink profile connect failed for 90:F2:60:0E:3C:8F: Protocol not available Jun 30 14:25:04 volumio volumio[1311]: info: Found bluetooth devices: { Jun 30 14:25:04 volumio volumio[1311]: "message": "pushBluetoothDevices", Jun 30 14:25:04 volumio volumio[1311]: "payload": { Jun 30 14:25:04 volumio volumio[1311]: "hasBluetooth": true, Jun 30 14:25:04 volumio volumio[1311]: "devices": [ Jun 30 14:25:04 volumio volumio[1311]: { Jun 30 14:25:04 volumio volumio[1311]: "mac": "90:F2:60:0E:3C:8F", Jun 30 14:25:04 volumio volumio[1311]: "name": "JBL Tuner 2", Jun 30 14:25:04 volumio volumio[1311]: "signal": 35, Jun 30 14:25:04 volumio volumio[1311]: "paired": "yes", Jun 30 14:25:04 volumio volumio[1311]: "trusted": "yes", Jun 30 14:25:04 volumio volumio[1311]: "icon": "", Jun 30 14:25:04 volumio volumio[1311]: "class": "", Jun 30 14:25:04 volumio volumio[1311]: "blocked": "", Jun 30 14:25:04 volumio volumio[1311]: "connected": "no", Jun 30 14:25:04 volumio volumio[1311]: "trycount": 1 Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: ] Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Jun 30 14:25:04 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Jun 30 14:25:04 volumio volumio[1311]: info: Starting bluetooth device scan Jun 30 14:25:04 volumio volumio[1311]: info: Found bluetooth devices: { Jun 30 14:25:04 volumio volumio[1311]: "message": "pushBluetoothDevices", Jun 30 14:25:04 volumio volumio[1311]: "payload": { Jun 30 14:25:04 volumio volumio[1311]: "hasBluetooth": true, Jun 30 14:25:04 volumio volumio[1311]: "devices": [ Jun 30 14:25:04 volumio volumio[1311]: { Jun 30 14:25:04 volumio volumio[1311]: "mac": "90:F2:60:0E:3C:8F", Jun 30 14:25:04 volumio volumio[1311]: "name": "JBL Tuner 2", Jun 30 14:25:04 volumio volumio[1311]: "signal": 35, Jun 30 14:25:04 volumio volumio[1311]: "paired": "yes", Jun 30 14:25:04 volumio volumio[1311]: "trusted": "yes", Jun 30 14:25:04 volumio volumio[1311]: "icon": "", Jun 30 14:25:04 volumio volumio[1311]: "class": "", Jun 30 14:25:04 volumio volumio[1311]: "blocked": "", Jun 30 14:25:04 volumio volumio[1311]: "connected": "no", Jun 30 14:25:04 volumio volumio[1311]: "trycount": 1 Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: ] Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:04 volumio volumio[1311]: } Jun 30 14:25:09 volumio go-librespot[1712]: time="2025-06-30T14:25:09+02:00" level=trace msg="sent dealer ping" Jun 30 14:25:09 volumio go-librespot[1712]: time="2025-06-30T14:25:09+02:00" level=trace msg="received dealer pong" Jun 30 14:25:09 volumio volumio[1311]: info: Device list changed: Jun 30 14:25:09 volumio volumio[1311]: info: { Jun 30 14:25:09 volumio volumio[1311]: "mac": "90:F2:60:0E:3C:8F", Jun 30 14:25:09 volumio volumio[1311]: "name": "JBL Tuner 2", Jun 30 14:25:09 volumio volumio[1311]: "signal": 37, Jun 30 14:25:09 volumio volumio[1311]: "paired": "yes", Jun 30 14:25:09 volumio volumio[1311]: "trusted": "yes", Jun 30 14:25:09 volumio volumio[1311]: "icon": "", Jun 30 14:25:09 volumio volumio[1311]: "class": "", Jun 30 14:25:09 volumio volumio[1311]: "blocked": "", Jun 30 14:25:09 volumio volumio[1311]: "connected": "no", Jun 30 14:25:09 volumio volumio[1311]: "trycount": 1 Jun 30 14:25:09 volumio volumio[1311]: } Jun 30 14:25:09 volumio volumio[1311]: info: AVRCP: adding device: 90:F2:60:0E:3C:8F Jun 30 14:25:09 volumio volumio[1311]: info: known: { Jun 30 14:25:09 volumio volumio[1311]: "90:F2:60:0E:3C:8F": { Jun 30 14:25:09 volumio volumio[1311]: "virtualPath": "", Jun 30 14:25:09 volumio volumio[1311]: "devicePath": "", Jun 30 14:25:09 volumio volumio[1311]: "inputEvent": null, Jun 30 14:25:09 volumio volumio[1311]: "keyboard": null Jun 30 14:25:09 volumio volumio[1311]: } Jun 30 14:25:09 volumio volumio[1311]: } Jun 30 14:25:12 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 14:25:12 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 30 14:25:22 volumio sudo[3160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 14:25:22 volumio sudo[3160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:25:22 volumio sudo[3160]: pam_unix(sudo:session): session closed for user root Jun 30 14:25:22 volumio sudo[3162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 14:25:22 volumio sudo[3162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:25:22 volumio sudo[3162]: pam_unix(sudo:session): session closed for user root Jun 30 14:25:22 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 30 14:25:22 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:25:22 volumio volumio[1311]: info: CorePlayQueue::getTrack 1 Jun 30 14:25:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 14:25:22 volumio volumio[1311]: info: Listing playlists Jun 30 14:25:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: info: Stopping bluetooth device scan Jun 30 14:25:24 volumio volumio[1311]: info: Stopping bluetooth device scan Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:24 volumio volumio[1311]: error: error Jun 30 14:25:26 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 14:25:26 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 30 14:25:35 volumio volumio[1311]: info: Starting Uninstall of plugin audio_interface - bluetooth_controller Jun 30 14:25:35 volumio volumio[1311]: info: Uninstalling plugin bluetooth_controller Jun 30 14:25:38 volumio go-librespot[1712]: time="2025-06-30T14:25:38+02:00" level=trace msg="received accesspoint ping" Jun 30 14:25:38 volumio go-librespot[1712]: time="2025-06-30T14:25:38+02:00" level=trace msg="received accesspoint pong ack" Jun 30 14:25:38 volumio go-librespot[1712]: time="2025-06-30T14:25:38+02:00" level=trace msg="sent dealer ping" Jun 30 14:25:39 volumio go-librespot[1712]: time="2025-06-30T14:25:39+02:00" level=trace msg="received dealer pong" Jun 30 14:25:46 volumio sudo[3199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 14:25:46 volumio sudo[3199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:25:46 volumio sudo[3199]: pam_unix(sudo:session): session closed for user root Jun 30 14:25:46 volumio sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 14:25:46 volumio sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 14:25:47 volumio sudo[3202]: pam_unix(sudo:session): session closed for user root Jun 30 14:25:47 volumio volumio[1311]: verbose: New Socket.io Connection to 192.168.33.27 from 192.168.33.28 UA: Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 30 14:25:47 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Jun 30 14:25:47 volumio volumio[1311]: info: CorePlayQueue::getTrack 1 Jun 30 14:25:47 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 14:25:47 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 14:25:47 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 30 14:25:51 volumio volumio[1311]: info: Enabling plugin bluetooth_controller Jun 30 14:25:51 volumio volumio[1311]: info: Loading plugin "bluetooth_controller"... Jun 30 14:25:52 volumio volumio[1311]: info: Enabling plugin bluetooth_controller Jun 30 14:25:52 volumio volumio[1311]: info: Loading plugin "bluetooth_controller"... Jun 30 14:25:53 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 14:25:53 volumio volumio[1311]: info: Discoverable: false Jun 30 14:25:54 volumio volumio[1311]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Jun 30 14:25:54 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Jun 30 14:25:54 volumio volumio[1311]: info: Starting bluetooth device scan Jun 30 14:25:54 volumio volumio[1311]: info: Found bluetooth devices: { Jun 30 14:25:54 volumio volumio[1311]: "message": "pushBluetoothDevices", Jun 30 14:25:54 volumio volumio[1311]: "payload": { Jun 30 14:25:54 volumio volumio[1311]: "hasBluetooth": true, Jun 30 14:25:54 volumio volumio[1311]: "devices": [ Jun 30 14:25:54 volumio volumio[1311]: { Jun 30 14:25:54 volumio volumio[1311]: "mac": "90:F2:60:0E:3C:8F", Jun 30 14:25:54 volumio volumio[1311]: "name": "JBL Tuner 2", Jun 30 14:25:54 volumio volumio[1311]: "signal": 37, Jun 30 14:25:54 volumio volumio[1311]: "paired": "yes", Jun 30 14:25:54 volumio volumio[1311]: "trusted": "yes", Jun 30 14:25:54 volumio volumio[1311]: "icon": "", Jun 30 14:25:54 volumio volumio[1311]: "class": "", Jun 30 14:25:54 volumio volumio[1311]: "blocked": "", Jun 30 14:25:54 volumio volumio[1311]: "connected": "no", Jun 30 14:25:54 volumio volumio[1311]: "trycount": 1 Jun 30 14:25:54 volumio volumio[1311]: } Jun 30 14:25:54 volumio volumio[1311]: ] Jun 30 14:25:54 volumio volumio[1311]: } Jun 30 14:25:54 volumio volumio[1311]: } Jun 30 14:25:55 volumio volumio[1311]: info: CALLMETHOD: audio_interface bluetooth_controller connectBluetoothDevice [object Object] Jun 30 14:25:55 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , connectBluetoothDevice Jun 30 14:25:55 volumio volumio[1311]: info: Connecting bluetooth device: 90:F2:60:0E:3C:8F Jun 30 14:25:55 volumio volumio[1311]: error: Failed callmethod call: TypeError: Cannot read property 'addDevice' of undefined Jun 30 14:25:55 volumio bluetoothd[792]: a2dp-sink profile connect failed for 90:F2:60:0E:3C:8F: Protocol not available Jun 30 14:25:55 volumio volumio[1311]: info: CALLMETHOD: audio_interface bluetooth_controller getBluetoothDevices [object Object] Jun 30 14:25:55 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: bluetooth_controller , getBluetoothDevices Jun 30 14:25:55 volumio volumio[1311]: info: Starting bluetooth device scan Jun 30 14:25:56 volumio volumio[1311]: info: Found bluetooth devices: { Jun 30 14:25:56 volumio volumio[1311]: "message": "pushBluetoothDevices", Jun 30 14:25:56 volumio volumio[1311]: "payload": { Jun 30 14:25:56 volumio volumio[1311]: "hasBluetooth": true, Jun 30 14:25:56 volumio volumio[1311]: "devices": [ Jun 30 14:25:56 volumio volumio[1311]: { Jun 30 14:25:56 volumio volumio[1311]: "mac": "90:F2:60:0E:3C:8F", Jun 30 14:25:56 volumio volumio[1311]: "name": "JBL Tuner 2", Jun 30 14:25:56 volumio volumio[1311]: "signal": 37, Jun 30 14:25:56 volumio volumio[1311]: "paired": "yes", Jun 30 14:25:56 volumio volumio[1311]: "trusted": "yes", Jun 30 14:25:56 volumio volumio[1311]: "icon": "", Jun 30 14:25:56 volumio volumio[1311]: "class": "", Jun 30 14:25:56 volumio volumio[1311]: "blocked": "", Jun 30 14:25:56 volumio volumio[1311]: "connected": "no", Jun 30 14:25:56 volumio volumio[1311]: "trycount": 1 Jun 30 14:25:56 volumio volumio[1311]: } Jun 30 14:25:56 volumio volumio[1311]: ] Jun 30 14:25:56 volumio volumio[1311]: } Jun 30 14:25:56 volumio volumio[1311]: } Jun 30 14:25:59 volumio volumio[1311]: info: Device list changed: Jun 30 14:25:59 volumio volumio[1311]: info: { Jun 30 14:25:59 volumio volumio[1311]: "mac": "90:F2:60:0E:3C:8F", Jun 30 14:25:59 volumio volumio[1311]: "name": "JBL Tuner 2", Jun 30 14:25:59 volumio volumio[1311]: "signal": 38, Jun 30 14:25:59 volumio volumio[1311]: "paired": "yes", Jun 30 14:25:59 volumio volumio[1311]: "trusted": "yes", Jun 30 14:25:59 volumio volumio[1311]: "icon": "", Jun 30 14:25:59 volumio volumio[1311]: "class": "", Jun 30 14:25:59 volumio volumio[1311]: "blocked": "", Jun 30 14:25:59 volumio volumio[1311]: "connected": "no", Jun 30 14:25:59 volumio volumio[1311]: "trycount": 1 Jun 30 14:25:59 volumio volumio[1311]: } Jun 30 14:25:59 volumio volumio[1311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 14:25:59 volumio volumio[1311]: TypeError: Cannot read property 'addDevice' of undefined Jun 30 14:25:59 volumio volumio[1311]: at BluetoothController.deviceListChanged (/data/plugins/user_interface/bluetooth_controller/index.js:222:35) Jun 30 14:25:59 volumio volumio[1311]: at EventEmitter. (/data/plugins/user_interface/bluetooth_controller/index.js:98:18) Jun 30 14:25:59 volumio volumio[1311]: at EventEmitter.emit (events.js:315:20) Jun 30 14:25:59 volumio volumio[1311]: at checkSignal (/data/plugins/user_interface/bluetooth_controller/node_modules/bluetoothctlwe/index.js:326:30) Jun 30 14:25:59 volumio volumio[1311]: at ReadStream. (/data/plugins/user_interface/bluetooth_controller/node_modules/bluetoothctlwe/index.js:169:9) Jun 30 14:25:59 volumio volumio[1311]: at ReadStream.emit (events.js:315:20) Jun 30 14:25:59 volumio volumio[1311]: at addChunk (internal/streams/readable.js:309:12) Jun 30 14:25:59 volumio volumio[1311]: at readableAddChunk (internal/streams/readable.js:280:11) Jun 30 14:25:59 volumio volumio[1311]: at ReadStream.Readable.push (internal/streams/readable.js:223:10) Jun 30 14:25:59 volumio volumio[1311]: at TTY.onStreamRead (internal/stream_base_commons.js:188:23) Jun 30 14:25:59 volumio volumio[1311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 14:26:00 volumio sudo[3254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 14:25 Jun 30 14:26:00 volumio sudo[3254]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"