-- Logs begin at Thu 2019-02-14 11:12:07 CET, end at Mon 2024-08-19 18:07:29 CEST. -- Aug 19 18:06:00 volumio volumio[1837]: checking info of 00:15:83:F6:49:54 Aug 19 18:06:00 volumio volumio[1837]: info: Device list changed: Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "48:5F:DE:88:27:A3", Aug 19 18:06:00 volumio volumio[1837]: "name": "SAKOBS", Aug 19 18:06:00 volumio volumio[1837]: "signal": 52, Aug 19 18:06:00 volumio volumio[1837]: "paired": "yes", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "yes", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "no", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: AVRCP: adding device: 48:5F:DE:88:27:A3 Aug 19 18:06:00 volumio volumio[1837]: info: known: { Aug 19 18:06:00 volumio volumio[1837]: "48:5F:DE:88:27:A3": { Aug 19 18:06:00 volumio volumio[1837]: "virtualPath": "", Aug 19 18:06:00 volumio volumio[1837]: "devicePath": "", Aug 19 18:06:00 volumio volumio[1837]: "inputEvent": null, Aug 19 18:06:00 volumio volumio[1837]: "keyboard": null Aug 19 18:06:00 volumio volumio[1837]: }, Aug 19 18:06:00 volumio volumio[1837]: "1C:12:B0:C7:E1:24": { Aug 19 18:06:00 volumio volumio[1837]: "virtualPath": "", Aug 19 18:06:00 volumio volumio[1837]: "devicePath": "", Aug 19 18:06:00 volumio volumio[1837]: "inputEvent": null, Aug 19 18:06:00 volumio volumio[1837]: "keyboard": null Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "69:75:EB:6F:35:2B", Aug 19 18:06:00 volumio volumio[1837]: "name": "LE-Bose Flex SoundLink", Aug 19 18:06:00 volumio volumio[1837]: "signal": 66, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "72:EA:A2:88:68:72", Aug 19 18:06:00 volumio volumio[1837]: "name": "NB4JS", Aug 19 18:06:00 volumio volumio[1837]: "signal": 0, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "00:7C:2D:DF:F1:63", Aug 19 18:06:00 volumio volumio[1837]: "name": "[TV] Samsung 7 Series (65)", Aug 19 18:06:00 volumio volumio[1837]: "signal": 82, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "10:97:BD:A8:B9:52", Aug 19 18:06:00 volumio volumio[1837]: "name": "CAF-LI401S", Aug 19 18:06:00 volumio volumio[1837]: "signal": 0, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "53:D9:83:B2:30:59", Aug 19 18:06:00 volumio volumio[1837]: "name": "LE-Bose Flex SoundLink", Aug 19 18:06:00 volumio volumio[1837]: "signal": 0, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "12:62:E5:CF:13:9D", Aug 19 18:06:00 volumio volumio[1837]: "name": "OfficeJet 5200 series", Aug 19 18:06:00 volumio volumio[1837]: "signal": 59, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "1C:12:B0:C7:E1:24", Aug 19 18:06:00 volumio volumio[1837]: "name": "Echo Dot-FK1", Aug 19 18:06:00 volumio volumio[1837]: "signal": 0, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: { Aug 19 18:06:00 volumio volumio[1837]: "mac": "00:15:83:F6:49:54", Aug 19 18:06:00 volumio volumio[1837]: "name": "PC-01", Aug 19 18:06:00 volumio volumio[1837]: "signal": 0, Aug 19 18:06:00 volumio volumio[1837]: "paired": "", Aug 19 18:06:00 volumio volumio[1837]: "trusted": "", Aug 19 18:06:00 volumio volumio[1837]: "icon": "", Aug 19 18:06:00 volumio volumio[1837]: "class": "", Aug 19 18:06:00 volumio volumio[1837]: "blocked": "", Aug 19 18:06:00 volumio volumio[1837]: "connected": "", Aug 19 18:06:00 volumio volumio[1837]: "trycount": 1 Aug 19 18:06:00 volumio volumio[1837]: } Aug 19 18:06:00 volumio volumio[1837]: info: Starting Uninstall of plugin audio_interface - bluetooth_controller Aug 19 18:06:00 volumio volumio[1837]: info: Uninstalling plugin bluetooth_controller Aug 19 18:06:05 volumio volumio[1837]: checking info of 00:15:83:F6:49:54 Aug 19 18:06:05 volumio volumio[1837]: info: Device list changed: Aug 19 18:06:05 volumio volumio[1837]: info: { Aug 19 18:06:05 volumio volumio[1837]: "mac": "48:5F:DE:88:27:A3", Aug 19 18:06:05 volumio volumio[1837]: "name": "SAKOBS", Aug 19 18:06:05 volumio volumio[1837]: "signal": 52, Aug 19 18:06:05 volumio volumio[1837]: "paired": "yes", Aug 19 18:06:05 volumio volumio[1837]: "trusted": "yes", Aug 19 18:06:05 volumio volumio[1837]: "icon": "", Aug 19 18:06:05 volumio volumio[1837]: "class": "", Aug 19 18:06:05 volumio volumio[1837]: "blocked": "", Aug 19 18:06:05 volumio volumio[1837]: "connected": "no", Aug 19 18:06:05 volumio volumio[1837]: "trycount": 4 Aug 19 18:06:05 volumio volumio[1837]: } Aug 19 18:06:05 volumio volumio[1837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 18:06:05 volumio volumio[1837]: TypeError: Cannot read property 'addDevice' of undefined Aug 19 18:06:05 volumio volumio[1837]: at BluetoothController.deviceListChanged (/data/plugins/audio_interface/bluetooth_controller/index.js:222:35) Aug 19 18:06:05 volumio volumio[1837]: at EventEmitter. (/data/plugins/audio_interface/bluetooth_controller/index.js:98:18) Aug 19 18:06:05 volumio volumio[1837]: at EventEmitter.emit (events.js:315:20) Aug 19 18:06:05 volumio volumio[1837]: at checkDevice (/data/plugins/audio_interface/bluetooth_controller/node_modules/bluetoothctlwe/index.js:396:48) Aug 19 18:06:05 volumio volumio[1837]: at ReadStream. (/data/plugins/audio_interface/bluetooth_controller/node_modules/bluetoothctlwe/index.js:167:9) Aug 19 18:06:05 volumio volumio[1837]: at ReadStream.emit (events.js:315:20) Aug 19 18:06:05 volumio volumio[1837]: at addChunk (internal/streams/readable.js:309:12) Aug 19 18:06:05 volumio volumio[1837]: at readableAddChunk (internal/streams/readable.js:280:11) Aug 19 18:06:05 volumio volumio[1837]: at ReadStream.Readable.push (internal/streams/readable.js:223:10) Aug 19 18:06:05 volumio volumio[1837]: at TTY.onStreamRead (internal/stream_base_commons.js:188:23) Aug 19 18:06:05 volumio volumio[1837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 18:06:08 volumio sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 18:05 Aug 19 18:06:08 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:06:09 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Aug 19 18:06:09 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:09] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Aug 19 18:06:09 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:09] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Aug 19 18:06:09 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:09] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Aug 19 18:06:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 19 18:06:09 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Aug 19 18:06:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 19 18:06:09 volumio systemd[1]: Started dynamicswap service. Aug 19 18:06:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 19 18:06:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 19 18:06:09 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 19 18:06:09 volumio systemd[1]: Stopped Volumio Backend Module. Aug 19 18:06:10 volumio systemd[1]: Started Volumio Backend Module. Aug 19 18:06:14 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:14] [info] asio async_connect error: system:111 (Connection refused) Aug 19 18:06:14 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 19 18:06:14 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:14] [error] handle_connect error: Underlying Transport Error Aug 19 18:06:18 volumio volumio[2453]: info: ------------------------------------------- Aug 19 18:06:18 volumio volumio[2453]: info: ----- Volumio3 ---- Aug 19 18:06:18 volumio volumio[2453]: info: ------------------------------------------- Aug 19 18:06:18 volumio volumio[2453]: info: ----- System startup ---- Aug 19 18:06:18 volumio volumio[2453]: info: ------------------------------------------- Aug 19 18:06:22 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:22] [connect] Successful connection Aug 19 18:06:22 volumio volumio[2453]: info: MYVOLUMIO Environment detected Aug 19 18:06:22 volumio volumio[2453]: info: Plugin folders cleanup Aug 19 18:06:22 volumio volumio[2453]: info: Scanning into folder /volumio/app/plugins/ Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category audio_interface Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category miscellanea Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category music_service Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category plugins.json Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category system_controller Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category user_interface Aug 19 18:06:22 volumio volumio[2453]: info: Scanning into folder /data/plugins/ Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category audio_interface Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category music_service Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category system_controller Aug 19 18:06:22 volumio volumio[2453]: info: Scanning category user_interface Aug 19 18:06:22 volumio volumio[2453]: info: Plugin folders cleanup completed Aug 19 18:06:22 volumio volumio[2453]: info: ------------------------------------------- Aug 19 18:06:22 volumio volumio[2453]: info: ----- Core plugins startup ---- Aug 19 18:06:22 volumio volumio[2453]: info: ------------------------------------------- Aug 19 18:06:22 volumio volumio[2453]: info: Loading plugins from folder /volumio/app/plugins/ Aug 19 18:06:22 volumio volumio[2453]: info: Adding plugin upnp to MyMusic Plugins Aug 19 18:06:22 volumio volumio[2453]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 19 18:06:22 volumio volumio[2453]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 19 18:06:22 volumio volumio[2453]: info: Loading plugins from folder /data/plugins/ Aug 19 18:06:22 volumio volumio[2453]: info: Loading plugin "system"... Aug 19 18:06:23 volumio volumio[2453]: info: Loading plugin "bluetooth_controller"... Aug 19 18:06:25 volumio volumio[2453]: info: Loading plugin "appearance"... Aug 19 18:06:29 volumio volumio[2453]: info: Loading plugin "network"... Aug 19 18:06:30 volumio volumio[2453]: info: Refreshing Cached IP Addresses Aug 19 18:06:30 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 18:06:30 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:06:30 volumio volumio[2453]: info: Loading plugin "services"... Aug 19 18:06:30 volumio sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 18:06:30 volumio volumio[2453]: info: Loading plugin "alsa_controller"... Aug 19 18:06:30 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Aug 19 18:06:30 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:06:30 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Aug 19 18:06:30 volumio sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 19 18:06:30 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:06:30 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 18:06:30 volumio volumio[2453]: info: Loading plugin "wizard"... Aug 19 18:06:30 volumio volumio[2453]: info: Loading plugin "networkfs"... Aug 19 18:06:31 volumio volumio[2453]: info: Starting Udev Watcher for removable devices Aug 19 18:06:31 volumio volumio[2453]: info: Ignoring mount for partition: boot Aug 19 18:06:31 volumio volumio[2453]: info: Ignoring mount for partition: volumio Aug 19 18:06:31 volumio volumio[2453]: info: Ignoring mount for partition: volumio_data Aug 19 18:06:31 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 18:06:31 volumio volumio[2453]: info: Loading plugin "volumio_command_line_client"... Aug 19 18:06:31 volumio volumio[2453]: info: Loading plugin "upnp"... Aug 19 18:06:31 volumio volumio[2453]: info: [1724083591317] Starting Upmpd Daemon Aug 19 18:06:31 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 18:06:31 volumio volumio[2453]: info: Loading plugin "my_music"... Aug 19 18:06:31 volumio volumio[2453]: info: Loading plugin "mpd"... Aug 19 18:06:31 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Aug 19 18:06:33 volumio volumio[2453]: info: Loading plugin "upnp_browser"... Aug 19 18:06:35 volumio volumio[2453]: info: Loading plugin "alarm-clock"... Aug 19 18:06:35 volumio volumio[2453]: info: Loading plugin "airplay_emulation"... Aug 19 18:06:35 volumio volumio[2453]: info: Starting Shairport Sync Aug 19 18:06:35 volumio volumio[2453]: info: Loading plugin "last_100"... Aug 19 18:06:35 volumio volumio[2453]: info: Loading plugin "webradio"... Aug 19 18:06:36 volumio ntpd[528]: 129.250.35.251 local addr 10.0.1.30 -> Aug 19 18:06:36 volumio volumio[2453]: info: Loading plugin "i2s_dacs"... Aug 19 18:06:36 volumio volumio[2453]: info: I2S DAC not set, start Auto-detection Aug 19 18:06:36 volumio volumio[2453]: info: Loading plugin "volumiodiscovery"... Aug 19 18:06:36 volumio volumio[2453]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 19 18:06:36 volumio node[2453]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 19 18:06:36 volumio volumio[2453]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 18:06:36 volumio node[2453]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 18:06:36 volumio volumio[2453]: *** WARNING *** For more information see Aug 19 18:06:36 volumio volumio[2453]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 19 18:06:36 volumio volumio[2453]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 18:06:36 volumio volumio[2453]: *** WARNING *** For more information see Aug 19 18:06:36 volumio node[2453]: *** WARNING *** For more information see Aug 19 18:06:36 volumio node[2453]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 19 18:06:36 volumio node[2453]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 19 18:06:36 volumio node[2453]: *** WARNING *** For more information see Aug 19 18:06:36 volumio volumio[2453]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 19 18:06:37 volumio volumio[2453]: info: Discovery: Started advertising with name: Volumio Aug 19 18:06:37 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:37] [connect] Successful connection Aug 19 18:06:37 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 18:06:37 volumio volumio[2453]: info: Loading plugin "youtube2"... Aug 19 18:06:44 volumio volumio[2453]: info: Loading plugin "outputs"... Aug 19 18:06:44 volumio volumio[2453]: info: Loading plugin "albumart"... Aug 19 18:06:44 volumio volumio[2453]: info: Plugin example_plugin is not enabled Aug 19 18:06:44 volumio volumio[2453]: info: Loading plugin "inputs"... Aug 19 18:06:44 volumio volumio[2453]: info: Loading plugin "updater_comm"... Aug 19 18:06:46 volumio volumio[2453]: info: Plugin mpdemulation is not enabled Aug 19 18:06:46 volumio volumio[2453]: info: Loading plugin "rest_api"... Aug 19 18:06:46 volumio volumio[2453]: info: Loading plugin "websocket"... Aug 19 18:06:46 volumio volumio[2453]: info: Starting Socket.io Server version 2.3.0 Aug 19 18:06:46 volumio volumio[2453]: info: Loading plugin "fusiondsp"... Aug 19 18:06:47 volumio volumio[2453]: Forking 1 albumart workers Aug 19 18:06:49 volumio volumio[2453]: info: Applying required configuration parameters for plugin fusiondsp Aug 19 18:06:49 volumio volumio[2453]: info: Loading plugin "backup_restore"... Aug 19 18:06:52 volumio volumio-remote-updater[1048]: [2024-08-19 18:06:52] [connect] Successful connection Aug 19 18:06:52 volumio volumio[2453]: info: Applying required configuration parameters for plugin backup_restore Aug 19 18:06:52 volumio volumio[2453]: info: Loading plugin "Systeminfo"... Aug 19 18:06:55 volumio volumio[2453]: Starting albumart workers Aug 19 18:06:57 volumio volumio[2453]: info: Loading i18n strings for locale de Aug 19 18:06:57 volumio volumio[2453]: Updating browse sources language Aug 19 18:06:57 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::initPlayerControls Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 19 18:06:59 volumio volumio[2453]: Express server listening on port 3000 Aug 19 18:06:59 volumio volumio[2453]: [Metrics] WebUI: 43s 476.70ms Aug 19 18:06:59 volumio volumio[2453]: info: CoreStateMachine::resetVolumioState Aug 19 18:06:59 volumio volumio[2453]: info: CoreStateMachine::getcurrentVolume Aug 19 18:06:59 volumio volumio[2453]: info: CoreCommandRouter::volumioRetrievevolume Aug 19 18:07:00 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 18:07:00 volumio sudo[2563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 18:07:00 volumio sudo[2563]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:07:00 volumio sudo[2561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 18:07:00 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:07:01 volumio sudo[2563]: pam_unix(sudo:session): session closed for user root Aug 19 18:07:01 volumio volumio[2453]: bluetooth controller exists Aug 19 18:07:01 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Aug 19 18:07:01 volumio volumio[2453]: adding device 00:15:83:F6:49:54 Aug 19 18:07:01 volumio volumio[2453]: adding device 48:5F:DE:88:27:A3 Aug 19 18:07:01 volumio volumio[2453]: adding device 69:75:EB:6F:35:2B Aug 19 18:07:01 volumio volumio[2453]: adding device 72:EA:A2:88:68:72 Aug 19 18:07:01 volumio volumio[2453]: adding device 00:7C:2D:DF:F1:63 Aug 19 18:07:01 volumio volumio[2453]: adding device 10:97:BD:A8:B9:52 Aug 19 18:07:01 volumio volumio[2453]: adding device 53:D9:83:B2:30:59 Aug 19 18:07:01 volumio volumio[2453]: adding device 12:62:E5:CF:13:9D Aug 19 18:07:01 volumio volumio[2453]: adding device 1C:12:B0:C7:E1:24 Aug 19 18:07:01 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 19 18:07:02 volumio volumio[2453]: verbose: New Socket.io Connection to 10.0.1.30 from 10.0.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 19 18:07:02 volumio volumio[2453]: info: Reloading queue from file Aug 19 18:07:02 volumio volumio[2453]: info: CoreCommandRouter::volumioGetState Aug 19 18:07:02 volumio volumio[2453]: info: CorePlayQueue::getTrack 0 Aug 19 18:07:02 volumio volumio[2453]: info: CoreStateMachine::setRepeat null single undefined Aug 19 18:07:02 volumio volumio[2453]: info: CoreStateMachine::pushState Aug 19 18:07:02 volumio volumio[2453]: info: CorePlayQueue::getTrack 0 Aug 19 18:07:02 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 18:07:02 volumio volumio[2453]: info: CoreCommandRouter::volumioPushState Aug 19 18:07:02 volumio volumio[2453]: info: CoreStateMachine::setRandom null Aug 19 18:07:02 volumio volumio[2453]: info: CoreStateMachine::pushState Aug 19 18:07:02 volumio volumio[2453]: info: CorePlayQueue::getTrack 0 Aug 19 18:07:02 volumio volumio[2453]: info: CoreCommandRouter::volumioPushState Aug 19 18:07:02 volumio volumio[2453]: info: Setting Device type: Raspberry PI Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 19 18:07:03 volumio volumio[2453]: info: Listing playlists Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::volumioGetVisibleSources Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::volumioGetQueue Aug 19 18:07:03 volumio volumio[2453]: info: CoreStateMachine::getQueue Aug 19 18:07:03 volumio volumio[2453]: info: CorePlayQueue::getQueue Aug 19 18:07:03 volumio volumio[2453]: info: VolumeController:: Volume=87 Mute =false Aug 19 18:07:03 volumio volumio[2453]: info: CoreStateMachine::pushState Aug 19 18:07:03 volumio volumio[2453]: info: CorePlayQueue::getTrack 0 Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::volumioPushState Aug 19 18:07:03 volumio volumio[2453]: info: CoreStateMachine::updateTrackBlock Aug 19 18:07:03 volumio volumio[2453]: info: CorePlayQueue::getTrackBlock Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::volumioRetrievevolume Aug 19 18:07:03 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 19 18:07:03 volumio sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 19 18:07:03 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 18:07:03 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Aug 19 18:07:04 volumio volumio[2453]: info: Discovery: adding a1803cbe-bea9-433a-b4f6-75c93b0b80cd Aug 19 18:07:04 volumio volumio[2453]: info: Discovery: Found device Volumio Aug 19 18:07:04 volumio volumio[2453]: info: CoreCommandRouter::volumioGetState Aug 19 18:07:04 volumio volumio[2453]: info: CorePlayQueue::getTrack 0 Aug 19 18:07:04 volumio volumio[2453]: info: Upmpdcli Daemon Started Aug 19 18:07:04 volumio volumio[2453]: info: VolumeController:: Volume=87 Mute =false Aug 19 18:07:04 volumio volumio[2453]: info: CoreStateMachine::pushState Aug 19 18:07:04 volumio volumio[2453]: info: CorePlayQueue::getTrack 0 Aug 19 18:07:04 volumio volumio[2453]: info: CoreCommandRouter::volumioPushState Aug 19 18:07:06 volumio volumio[2453]: checking info of 00:15:83:F6:49:54 Aug 19 18:07:06 volumio volumio[2453]: checking info of 48:5F:DE:88:27:A3 Aug 19 18:07:06 volumio volumio[2453]: checking info of 69:75:EB:6F:35:2B Aug 19 18:07:06 volumio volumio[2453]: checking info of 72:EA:A2:88:68:72 Aug 19 18:07:06 volumio volumio[2453]: checking info of 00:7C:2D:DF:F1:63 Aug 19 18:07:06 volumio volumio[2453]: checking info of 10:97:BD:A8:B9:52 Aug 19 18:07:06 volumio volumio[2453]: checking info of 53:D9:83:B2:30:59 Aug 19 18:07:06 volumio volumio[2453]: checking info of 12:62:E5:CF:13:9D Aug 19 18:07:06 volumio volumio[2453]: checking info of 1C:12:B0:C7:E1:24 Aug 19 18:07:07 volumio volumio-remote-updater[1048]: [2024-08-19 18:07:07] [connect] Successful connection Aug 19 18:07:07 volumio volumio-remote-updater[1048]: [2024-08-19 18:07:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724083627 101 Aug 19 18:07:07 volumio volumio[2453]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Aug 19 18:07:11 volumio volumio[2453]: checking info of 00:15:83:F6:49:54 Aug 19 18:07:11 volumio volumio[2453]: checking info of 48:5F:DE:88:27:A3 Aug 19 18:07:11 volumio volumio[2453]: checking info of 69:75:EB:6F:35:2B Aug 19 18:07:11 volumio volumio[2453]: checking info of 72:EA:A2:88:68:72 Aug 19 18:07:11 volumio volumio[2453]: checking info of 00:7C:2D:DF:F1:63 Aug 19 18:07:11 volumio volumio[2453]: checking info of 10:97:BD:A8:B9:52 Aug 19 18:07:11 volumio volumio[2453]: checking info of 53:D9:83:B2:30:59 Aug 19 18:07:11 volumio volumio[2453]: checking info of 12:62:E5:CF:13:9D Aug 19 18:07:11 volumio volumio[2453]: checking info of 1C:12:B0:C7:E1:24 Aug 19 18:07:14 volumio volumio[2453]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 18:07:16 volumio volumio[2453]: checking info of 00:15:83:F6:49:54 Aug 19 18:07:16 volumio volumio[2453]: checking info of 48:5F:DE:88:27:A3 Aug 19 18:07:16 volumio volumio[2453]: checking info of 69:75:EB:6F:35:2B Aug 19 18:07:16 volumio volumio[2453]: checking info of 72:EA:A2:88:68:72 Aug 19 18:07:16 volumio volumio[2453]: checking info of 00:7C:2D:DF:F1:63 Aug 19 18:07:16 volumio volumio[2453]: checking info of 10:97:BD:A8:B9:52 Aug 19 18:07:16 volumio volumio[2453]: checking info of 53:D9:83:B2:30:59 Aug 19 18:07:16 volumio volumio[2453]: checking info of 12:62:E5:CF:13:9D Aug 19 18:07:16 volumio volumio[2453]: checking info of 1C:12:B0:C7:E1:24 Aug 19 18:07:21 volumio volumio[2453]: checking info of 00:15:83:F6:49:54 Aug 19 18:07:21 volumio volumio[2453]: checking info of 48:5F:DE:88:27:A3 Aug 19 18:07:21 volumio volumio[2453]: checking info of 69:75:EB:6F:35:2B Aug 19 18:07:21 volumio volumio[2453]: checking info of 72:EA:A2:88:68:72 Aug 19 18:07:21 volumio volumio[2453]: checking info of 00:7C:2D:DF:F1:63 Aug 19 18:07:21 volumio volumio[2453]: checking info of 10:97:BD:A8:B9:52 Aug 19 18:07:21 volumio volumio[2453]: checking info of 53:D9:83:B2:30:59 Aug 19 18:07:21 volumio volumio[2453]: checking info of 12:62:E5:CF:13:9D Aug 19 18:07:21 volumio volumio[2453]: checking info of 1C:12:B0:C7:E1:24 Aug 19 18:07:22 volumio volumio[2453]: info: CoreCommandRouter::Close All Modals sent Aug 19 18:07:27 volumio volumio[2453]: error: Plugin audio_interface bluetooth_controller failed to complete 'onVolumioStart' in a timely fashion Aug 19 18:07:27 volumio volumio[2453]: info: Preparing to generate the ALSA configuration file Aug 19 18:07:27 volumio volumio[2453]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 19 18:07:27 volumio volumio[2453]: info: Reading ALSA contributions from plugins. Aug 19 18:07:27 volumio volumio[2453]: info: Starting Uninstall of plugin audio_interface - bluetooth_controller Aug 19 18:07:27 volumio volumio[2453]: info: Uninstalling plugin bluetooth_controller Aug 19 18:07:27 volumio volumio[2453]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 18:07:27 volumio volumio[2453]: TypeError: Cannot read property 'close' of undefined Aug 19 18:07:27 volumio volumio[2453]: at BluetoothController.onStop (/data/plugins/audio_interface/bluetooth_controller/index.js:137:21) Aug 19 18:07:27 volumio volumio[2453]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 19 18:07:27 volumio volumio[2453]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Aug 19 18:07:27 volumio volumio[2453]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Aug 19 18:07:27 volumio volumio[2453]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Aug 19 18:07:27 volumio volumio[2453]: at Socket.emit (events.js:315:20) Aug 19 18:07:27 volumio volumio[2453]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 19 18:07:27 volumio volumio[2453]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 18:07:27 volumio volumio[2453]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 18:07:29 volumio sudo[2591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 18:06 Aug 19 18:07:29 volumio sudo[2591]: 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"