Oct 14 12:10:00 volumio volumio[16017]: BT PLUGIN MESSAGE: SYNC attempt 1 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 12:10:00 volumio volumio[16017]: BT PLUGIN MESSAGE: SYNC: retrying in 10s Oct 14 12:10:04 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:10:05 volumio volumio[16017]: info: BOOT COMPLETED Oct 14 12:10:08 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:10:10 volumio volumio[16017]: BT PLUGIN MESSAGE: SYNC attempt 2 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 12:10:10 volumio volumio[16017]: BT PLUGIN MESSAGE: SYNC: retrying in 10s Oct 14 12:10:16 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:10:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:10:19 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 12:10:20 volumio volumio[16017]: BT PLUGIN MESSAGE: SYNC attempt 3 err=Command failed: /bin/busctl tree org.bluez 2>/dev/null | grep -oE "/org/bluez/hc out=empty Oct 14 12:10:24 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:10:31 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:10:32 volumio sshd[16619]: Accepted password for volumio from 10.0.0.219 port 49534 ssh2 Oct 14 12:10:32 volumio sshd[16619]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 12:10:32 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Oct 14 12:10:32 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 12:10:32 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:32 volumio systemd-logind[745]: New session 63 of user volumio. Oct 14 12:10:32 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:32 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 12:10:32 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 12:10:32 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Oct 14 12:10:32 volumio (systemd)[16622]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 12:10:32 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:34 volumio systemd[16622]: Queued start job for default target default.target. Oct 14 12:10:34 volumio systemd[16622]: Created slice app.slice - User Application Slice. Oct 14 12:10:34 volumio systemd[16622]: Reached target paths.target - Paths. Oct 14 12:10:34 volumio systemd[16622]: Reached target timers.target - Timers. Oct 14 12:10:34 volumio systemd[16622]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Oct 14 12:10:34 volumio systemd[16622]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 12:10:34 volumio systemd[16622]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 12:10:34 volumio systemd[16622]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 12:10:34 volumio systemd[16622]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 12:10:34 volumio systemd[16622]: Reached target sockets.target - Sockets. Oct 14 12:10:34 volumio systemd[16622]: Reached target basic.target - Basic System. Oct 14 12:10:34 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Oct 14 12:10:34 volumio systemd[16622]: Started mpris-proxy.service - Bluetooth mpris proxy. Oct 14 12:10:34 volumio systemd[16622]: Reached target default.target - Main User Target. Oct 14 12:10:34 volumio systemd[16622]: Startup finished in 1.388s. Oct 14 12:10:34 volumio systemd[1]: Started session-63.scope - Session 63 of User volumio. Oct 14 12:10:34 volumio mpris-proxy[16644]: Can't get on session bus Oct 14 12:10:34 volumio systemd[16622]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Oct 14 12:10:34 volumio systemd[16622]: mpris-proxy.service: Failed with result 'exit-code'. Oct 14 12:10:34 volumio sshd[16619]: pam_env(sshd:session): deprecated reading of user environment enabled Oct 14 12:10:34 volumio sudo[16657]: volumio : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cat /data/configuration/plugins.json Oct 14 12:10:34 volumio sudo[16657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:10:34 volumio sudo[16657]: pam_unix(sudo:session): session closed for user root Oct 14 12:10:34 volumio sshd[16654]: Received disconnect from 10.0.0.219 port 49534:11: disconnected by user Oct 14 12:10:34 volumio sshd[16654]: Disconnected from user volumio 10.0.0.219 port 49534 Oct 14 12:10:34 volumio sshd[16619]: pam_unix(sshd:session): session closed for user volumio Oct 14 12:10:34 volumio systemd[1]: session-63.scope: Deactivated successfully. Oct 14 12:10:34 volumio systemd-logind[745]: Session 63 logged out. Waiting for processes to exit. Oct 14 12:10:34 volumio systemd-logind[745]: Removed session 63. Oct 14 12:10:44 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Oct 14 12:10:44 volumio systemd[16622]: Activating special unit exit.target... Oct 14 12:10:44 volumio systemd[16622]: Stopped target default.target - Main User Target. Oct 14 12:10:44 volumio systemd[16622]: Stopped target basic.target - Basic System. Oct 14 12:10:44 volumio systemd[16622]: Stopped target paths.target - Paths. Oct 14 12:10:44 volumio systemd[16622]: Stopped target sockets.target - Sockets. Oct 14 12:10:44 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:44 volumio systemd[16622]: Stopped target timers.target - Timers. Oct 14 12:10:44 volumio systemd[16622]: Closed dirmngr.socket - GnuPG network certificate management daemon. Oct 14 12:10:44 volumio systemd[16622]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 12:10:44 volumio systemd[16622]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 12:10:44 volumio systemd[16622]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 12:10:44 volumio systemd[16622]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 12:10:44 volumio systemd[16622]: Removed slice app.slice - User Application Slice. Oct 14 12:10:44 volumio systemd[16622]: Reached target shutdown.target - Shutdown. Oct 14 12:10:44 volumio systemd[16622]: Finished systemd-exit.service - Exit the Session. Oct 14 12:10:44 volumio systemd[16622]: Reached target exit.target - Exit the Session. Oct 14 12:10:44 volumio systemd[1]: user@1000.service: Deactivated successfully. Oct 14 12:10:44 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Oct 14 12:10:44 volumio systemd[1]: user@1000.service: Consumed 1.405s CPU time. Oct 14 12:10:45 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 12:10:45 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:45 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Oct 14 12:10:45 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Oct 14 12:10:45 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 12:10:45 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Oct 14 12:10:45 volumio systemd[1]: user-1000.slice: Consumed 1.913s CPU time. Oct 14 12:10:56 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:10:59 volumio sshd[16699]: Accepted password for volumio from 10.0.0.219 port 49568 ssh2 Oct 14 12:10:59 volumio sshd[16699]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 12:10:59 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Oct 14 12:10:59 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 12:10:59 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:59 volumio systemd-logind[745]: New session 65 of user volumio. Oct 14 12:10:59 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 12:10:59 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:59 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 12:10:59 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Oct 14 12:10:59 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:10:59 volumio (systemd)[16716]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 12:11:00 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:11:00 volumio systemd[16716]: Queued start job for default target default.target. Oct 14 12:11:00 volumio systemd[16716]: Created slice app.slice - User Application Slice. Oct 14 12:11:00 volumio systemd[16716]: Reached target paths.target - Paths. Oct 14 12:11:00 volumio systemd[16716]: Reached target timers.target - Timers. Oct 14 12:11:00 volumio systemd[16716]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Oct 14 12:11:00 volumio systemd[16716]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 12:11:00 volumio systemd[16716]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 12:11:00 volumio systemd[16716]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 12:11:00 volumio systemd[16716]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 12:11:00 volumio systemd[16716]: Reached target sockets.target - Sockets. Oct 14 12:11:00 volumio systemd[16716]: Reached target basic.target - Basic System. Oct 14 12:11:00 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Oct 14 12:11:00 volumio systemd[16716]: Started mpris-proxy.service - Bluetooth mpris proxy. Oct 14 12:11:00 volumio systemd[16716]: Reached target default.target - Main User Target. Oct 14 12:11:00 volumio systemd[16716]: Startup finished in 1.313s. Oct 14 12:11:00 volumio systemd[1]: Started session-65.scope - Session 65 of User volumio. Oct 14 12:11:00 volumio mpris-proxy[16732]: Can't get on session bus Oct 14 12:11:00 volumio systemd[16716]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Oct 14 12:11:00 volumio systemd[16716]: mpris-proxy.service: Failed with result 'exit-code'. Oct 14 12:11:00 volumio sshd[16699]: pam_env(sshd:session): deprecated reading of user environment enabled Oct 14 12:11:01 volumio sudo[16748]: volumio : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cat /data/configuration/plugins.json Oct 14 12:11:01 volumio sudo[16748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:11:01 volumio sudo[16748]: pam_unix(sudo:session): session closed for user root Oct 14 12:11:01 volumio sshd[16742]: Received disconnect from 10.0.0.219 port 49568:11: disconnected by user Oct 14 12:11:01 volumio sshd[16742]: Disconnected from user volumio 10.0.0.219 port 49568 Oct 14 12:11:01 volumio sshd[16699]: pam_unix(sshd:session): session closed for user volumio Oct 14 12:11:01 volumio systemd[1]: session-65.scope: Deactivated successfully. Oct 14 12:11:01 volumio systemd-logind[745]: Session 65 logged out. Waiting for processes to exit. Oct 14 12:11:01 volumio systemd-logind[745]: Removed session 65. Oct 14 12:11:06 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:11:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:11:11 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Oct 14 12:11:11 volumio systemd[16716]: Activating special unit exit.target... Oct 14 12:11:11 volumio systemd[16716]: Stopped target default.target - Main User Target. Oct 14 12:11:11 volumio systemd[16716]: Stopped target basic.target - Basic System. Oct 14 12:11:11 volumio systemd[16716]: Stopped target paths.target - Paths. Oct 14 12:11:11 volumio systemd[16716]: Stopped target sockets.target - Sockets. Oct 14 12:11:11 volumio systemd[16716]: Stopped target timers.target - Timers. Oct 14 12:11:11 volumio systemd[16716]: Closed dirmngr.socket - GnuPG network certificate management daemon. Oct 14 12:11:11 volumio systemd[16716]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 12:11:11 volumio systemd[16716]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 12:11:11 volumio systemd[16716]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 12:11:11 volumio systemd[16716]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 12:11:11 volumio systemd[16716]: Removed slice app.slice - User Application Slice. Oct 14 12:11:11 volumio systemd[16716]: Reached target shutdown.target - Shutdown. Oct 14 12:11:11 volumio systemd[16716]: Finished systemd-exit.service - Exit the Session. Oct 14 12:11:11 volumio systemd[16716]: Reached target exit.target - Exit the Session. Oct 14 12:11:11 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:11:11 volumio systemd[1]: user@1000.service: Deactivated successfully. Oct 14 12:11:11 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Oct 14 12:11:11 volumio systemd[1]: user@1000.service: Consumed 1.413s CPU time. Oct 14 12:11:12 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 12:11:12 volumio volumio[16017]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 12:11:12 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Oct 14 12:11:12 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Oct 14 12:11:12 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 12:11:12 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Oct 14 12:11:12 volumio systemd[1]: user-1000.slice: Consumed 2.323s CPU time. Oct 14 12:11:14 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:11:21 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:11:25 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:11:29 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:11:35 volumio kernel: hwmon hwmon1: Voltage normalised Oct 14 12:11:39 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 14 12:11:40 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 12:11:40 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 12:11:41 volumio sudo[16841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 12:11:41 volumio sudo[16841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:11:41 volumio sudo[16841]: pam_unix(sudo:session): session closed for user root Oct 14 12:11:41 volumio sudo[16843]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 12:11:41 volumio sudo[16843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 12:11:41 volumio sudo[16843]: pam_unix(sudo:session): session closed for user root Oct 14 12:11:41 volumio volumio[16017]: verbose: New Socket.io Connection to 10.0.0.163 from 10.0.0.238 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::volumioGetState Oct 14 12:11:41 volumio volumio[16017]: info: CorePlayQueue::getTrack 0 Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 12:11:41 volumio volumio[16017]: info: Retrieving Cloud Streaming UI Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 12:11:41 volumio volumio[16017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Oct 14 12:11:41 volumio volumio[16017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 12:11:41 volumio volumio[16017]: TypeError: self.commandRouter.executeOnPlugin(...).then is not a function Oct 14 12:11:41 volumio volumio[16017]: at my_module.getMultiroom (/myvolumio/plugins/audio_interface/multiroom/index.js:13:14) Oct 14 12:11:41 volumio volumio[16017]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 14 12:11:41 volumio volumio[16017]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:811:45) Oct 14 12:11:41 volumio volumio[16017]: at Socket.emit (node:events:514:28) Oct 14 12:11:41 volumio volumio[16017]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 14 12:11:41 volumio volumio[16017]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 14 12:11:41 volumio volumio[16017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 12:11:42 volumio sudo[16860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 12:10' Oct 14 12:11:42 volumio sudo[16860]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"