Feb 11 23:25:31 volumio2 ntpd[1016]: CLOCK: time stepped by 765849.187518
Feb 11 23:25:31 volumio2 ntpd[1016]: CLOCK: time changed from 2026-02-03 to 2026-02-11
Feb 11 23:25:31 volumio2 ntpd[1016]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Feb 11 23:25:31 volumio2 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Feb 11 23:25:31 volumio2 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats.
Feb 11 23:25:31 volumio2 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully.
Feb 11 23:25:31 volumio2 systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Feb 11 23:25:31 volumio2 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "outputs"...
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "albumart"...
Feb 11 23:25:31 volumio2 volumio[1193]: info: Plugin example_plugin is not enabled
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "inputs"...
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "updater_comm"...
Feb 11 23:25:31 volumio2 volumio[1193]: info: Plugin mpdemulation is not enabled
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "rest_api"...
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "websocket"...
Feb 11 23:25:31 volumio2 volumio[1193]: info: Starting Socket.io Server version 1.7.4
Feb 11 23:25:31 volumio2 volumio[1193]: info: Plugin minidlna is not enabled
Feb 11 23:25:31 volumio2 volumio[1193]: info: Loading plugin "backup_restore"...
Feb 11 23:25:32 volumio2 volumio[1193]: info: Applying required configuration parameters for plugin backup_restore
Feb 11 23:25:32 volumio2 volumio[1193]: info: Loading plugin "Bluetoothremote"...
Feb 11 23:25:32 volumio2 volumio[1475]: Forking 3 albumart workers
Feb 11 23:25:34 volumio2 volumio[1193]: info: Applying required configuration parameters for plugin Bluetoothremote
Feb 11 23:25:34 volumio2 volumio[1193]: info: Plugin Systeminfo is not enabled
Feb 11 23:25:34 volumio2 volumio[1193]: info: Loading i18n strings for locale en
Feb 11 23:25:34 volumio2 volumio[1193]: Updating browse sources language
Feb 11 23:25:34 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::initPlayerControls
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: Express server listening on port 3000
Feb 11 23:25:35 volumio2 volumio[1193]: [Metrics] WebUI: 14s 825.41ms
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreStateMachine::resetVolumioState
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreStateMachine::getcurrentVolume
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::volumioRetrievevolume
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreStateMachine::getcurrentVolume
Feb 11 23:25:35 volumio2 volumio[1193]: info: CoreCommandRouter::volumioRetrievevolume
Feb 11 23:25:35 volumio2 sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 11 23:25:35 volumio2 sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:35 volumio2 sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 11 23:25:35 volumio2 sudo[1536]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:35 volumio2 sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:35 volumio2 sudo[1538]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:35 volumio2 volumio[1193]: info: Volumio Network Manager: Network status updated: 1
Feb 11 23:25:36 volumio2 volumio[1193]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'.
Feb 11 23:25:36 volumio2 volumio[1193]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'.
Feb 11 23:25:36 volumio2 volumio[1193]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Feb 11 23:25:36 volumio2 volumio[1193]: wlan0 Interface doesn't support scanning : Device or resource busy
Feb 11 23:25:36 volumio2 volumio[1193]: info: Cannot use regular scanning, forcing with ap-force
Feb 11 23:25:36 volumio2 sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Feb 11 23:25:36 volumio2 sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:36 volumio2 volumio[1487]: Starting albumart workers
Feb 11 23:25:37 volumio2 volumio[1486]: Starting albumart workers
Feb 11 23:25:38 volumio2 volumio[1485]: Starting albumart workers
Feb 11 23:25:38 volumio2 volumio-remote-updater[691]: [2026-02-11 23:25:38] [connect] Successful connection
Feb 11 23:25:38 volumio2 sudo[1545]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:39 volumio2 systemd[1]: systemd-fsckd.service: Deactivated successfully.
Feb 11 23:25:39 volumio2 systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Feb 11 23:25:39 volumio2 systemd[1]: e2scrub_all.service: Deactivated successfully.
Feb 11 23:25:39 volumio2 systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Feb 11 23:25:39 volumio2 volumio[1193]: info: VolumeController:: Volume=undefined Mute =false
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::pushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioPushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::updateTrackBlock
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrackBlock
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioRetrievevolume
Feb 11 23:25:39 volumio2 volumio[1193]: info: VolumeController:: Volume=undefined Mute =false
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::pushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioPushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::updateTrackBlock
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrackBlock
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioRetrievevolume
Feb 11 23:25:39 volumio2 volumio-remote-updater[691]: [2026-02-11 23:25:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770848738 101
Feb 11 23:25:39 volumio2 volumio[1193]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1
Feb 11 23:25:39 volumio2 volumio[1193]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'.
Feb 11 23:25:39 volumio2 volumio[1193]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'.
Feb 11 23:25:39 volumio2 volumio[1193]: info: Reloading queue from file
Feb 11 23:25:39 volumio2 volumio[1193]: info: VolumeController:: Volume=undefined Mute =false
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::pushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioPushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: VolumeController:: Volume=undefined Mute =false
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::pushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioPushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::setRepeat null single undefined
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::pushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioPushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::setRandom true
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreStateMachine::pushState
Feb 11 23:25:39 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 11 23:25:39 volumio2 volumio[1193]: info: CoreCommandRouter::volumioPushState
Feb 11 23:25:40 volumio2 volumio[1193]: info: Setting Device type: Raspberry PI
Feb 11 23:25:40 volumio2 volumio[1193]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Feb 11 23:25:40 volumio2 volumio[1193]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112
Feb 11 23:25:40 volumio2 volumio[1193]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: A device disappeared from network
Feb 11 23:25:40 volumio2 sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 11 23:25:40 volumio2 sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: adding e05999c0-6f5a-4c27-9402-f4f52fec1b04
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: Found device Volumio2
Feb 11 23:25:40 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:40 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: A device disappeared from network
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: Device volumio2 disappeared from network
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: A device disappeared from network
Feb 11 23:25:40 volumio2 volumio[1193]: info: Completed loading Core Plugins
Feb 11 23:25:40 volumio2 volumio[1193]: info: Preparing to generate the ALSA configuration file
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: adding e05999c0-6f5a-4c27-9402-f4f52fec1b04
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: Found device Volumio2
Feb 11 23:25:40 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:40 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: adding 67e33b0d-27ee-456e-9450-17d79d69e6e8
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: Found device Volumio1
Feb 11 23:25:40 volumio2 volumio[1193]: info: Discovery: Connecting to remote: 192.168.178.14
Feb 11 23:25:40 volumio2 volumio[1193]: info: Asound.conf file unchanged, so no further update is needed
Feb 11 23:25:40 volumio2 volumio[1193]: info: Output device has changed, restarting MPD
Feb 11 23:25:40 volumio2 volumio[1193]: info: Output device has changed, restarting Shairport Sync
Feb 11 23:25:40 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:40 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:40 volumio2 sudo[1588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 11 23:25:40 volumio2 sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:40 volumio2 sudo[1590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 11 23:25:40 volumio2 sudo[1588]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:40 volumio2 volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 11 23:25:40 volumio2 volumio[1193]: info: ___________ START PLUGINS ___________
Feb 11 23:25:40 volumio2 sudo[1590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:40 volumio2 volumio[1193]: info: ControllerMpd::onStart: Initializing MPD
Feb 11 23:25:40 volumio2 volumio[1193]: info: Creating MPD Configuration file
Feb 11 23:25:41 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 11 23:25:41 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 11 23:25:41 volumio2 sudo[1598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 11 23:25:41 volumio2 volumio[1193]: info: [1770848741059] CoreMusicLibrary::Adding element Media Servers
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 11 23:25:41 volumio2 sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 11 23:25:41 volumio2 sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:41 volumio2 sudo[1601]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:41 volumio2 sudo[1604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 11 23:25:41 volumio2 sudo[1604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:41 volumio2 sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:41 volumio2 volumio[1193]: info: UPNP Browser: Client initialized successfully
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:41 volumio2 sudo[1603]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 11 23:25:41 volumio2 sudo[1603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 11 23:25:41 volumio2 sudo[1609]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 11 23:25:41 volumio2 sudo[1603]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:41 volumio2 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Feb 11 23:25:41 volumio2 systemd[1]: mpd.service: Deactivated successfully.
Feb 11 23:25:41 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 11 23:25:41 volumio2 systemd[1]: mpd.socket: Deactivated successfully.
Feb 11 23:25:41 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 11 23:25:41 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 11 23:25:41 volumio2 sudo[1598]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:41 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 11 23:25:41 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 11 23:25:41 volumio2 volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:41 volumio2 sudo[1616]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 11 23:25:41 volumio2 sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 11 23:25:41 volumio2 sudo[1627]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Feb 11 23:25:41 volumio2 sudo[1616]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:41 volumio2 volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 11 23:25:41 volumio2 volumio[1193]: info: [1770848741506] CoreMusicLibrary::Adding element Last_100
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 11 23:25:41 volumio2 volumio[1193]: info: [1770848741523] CoreMusicLibrary::Adding element Webradio
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 23:25:41 volumio2 volumio[1193]: info: Initializing BBC Radios
Feb 11 23:25:41 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:41.769+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z
Feb 11 23:25:41 volumio2 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Feb 11 23:25:41 volumio2 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:41 volumio2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Feb 11 23:25:41 volumio2 sudo[1576]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 11 23:25:41 volumio2 volumio[1193]: info: [1770848741882] CoreMusicLibrary::Adding element Bandcamp Discover
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 11 23:25:41 volumio2 volumio[1193]: Cannot find translation for source Bandcamp Discover
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 11 23:25:41 volumio2 volumio[1193]: info: [1770848741966] CoreMusicLibrary::Adding element SoundCloud
Feb 11 23:25:41 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 11 23:25:41 volumio2 volumio[1193]: Cannot find translation for source Bandcamp Discover
Feb 11 23:25:41 volumio2 volumio[1193]: Cannot find translation for source SoundCloud
Feb 11 23:25:42 volumio2 volumio[1193]: info: Creating Spotify config file
Feb 11 23:25:42 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:42 volumio2 volumio[1193]: info: Loading i18n strings for locale en
Feb 11 23:25:42 volumio2 volumio[1193]: info: Volumio Calling Home
Feb 11 23:25:43 volumio2 volumio[1193]: error: Failed to write spotify config file: Error: ENOENT: no such file or directory, open '/data/go-librespot/config.yml'
Feb 11 23:25:43 volumio2 volumio[1193]: error: Error initializing go-librespot daemon: Error: ENOENT: no such file or directory, open '/data/go-librespot/config.yml'
Feb 11 23:25:43 volumio2 volumio[1193]: info: Discovery: this is already registered, e05999c0-6f5a-4c27-9402-f4f52fec1b04
Feb 11 23:25:43 volumio2 volumio[1193]: info: Discovery: Found device Volumio2
Feb 11 23:25:43 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:43 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:43 volumio2 volumio[1193]: info: Discovery: this is already registered, 67e33b0d-27ee-456e-9450-17d79d69e6e8
Feb 11 23:25:43 volumio2 volumio[1193]: info: Discovery: Found device Volumio1
Feb 11 23:25:43 volumio2 volumio[1193]: info: Discovery: Connecting to remote: 192.168.178.14
Feb 11 23:25:44 volumio2 volumio[1193]: info: MPD Permissions set
Feb 11 23:25:44 volumio2 volumio[1193]: info: MPD Permissions set
Feb 11 23:25:44 volumio2 volumio[1193]: info: Upmpdcli Daemon Started
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.17' (uid=0 pid=1608 comm="/usr/bin/volumio5-onboarding")
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.123+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="could not activate BlueZ adapter: Unit dbus-org.bluez.service not found."
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.17' (uid=0 pid=1608 comm="/usr/bin/volumio5-onboarding")
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.125+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="could not activate BlueZ adapter: Unit dbus-org.bluez.service not found."
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.17' (uid=0 pid=1608 comm="/usr/bin/volumio5-onboarding")
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.129+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="could not activate BlueZ adapter: Unit dbus-org.bluez.service not found."
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.17' (uid=0 pid=1608 comm="/usr/bin/volumio5-onboarding")
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Feb 11 23:25:44 volumio2 volumio[1193]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.139+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="could not activate BlueZ adapter: Unit dbus-org.bluez.service not found."
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.17' (uid=0 pid=1608 comm="/usr/bin/volumio5-onboarding")
Feb 11 23:25:44 volumio2 dbus-daemon[678]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.141+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="could not activate BlueZ adapter: Unit dbus-org.bluez.service not found."
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.141+01:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled"
Feb 11 23:25:44 volumio2 volumio[1193]: info: Volumio called home
Feb 11 23:25:44 volumio2 volumio[1193]: info: Discovery: this is already registered, e05999c0-6f5a-4c27-9402-f4f52fec1b04
Feb 11 23:25:44 volumio2 volumio[1193]: info: Discovery: Found device Volumio2
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:44 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:44 volumio2 volumio[1193]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2
Feb 11 23:25:44 volumio2 volumio[1193]: info: Received Get System Info
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 11 23:25:44 volumio2 volumio[1193]: info: Discovery: Getting this device information
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:44 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.195+01:00 level=INFO msg="system info for bb7f0e8c63653bf6ec635029ae90f2ee" deviceName=Volumio2 deviceVariant=volumio deviceModel= softwareVersion=4.095
Feb 11 23:25:44 volumio2 volumio[1193]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:25:44 volumio2 volumio[1193]: info: No need to fix Spotify hosts
Feb 11 23:25:44 volumio2 volumio[1193]: info: Received Get System Info
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 11 23:25:44 volumio2 volumio[1193]: info: Discovery: Getting this device information
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:44 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.479+01:00 level=INFO msg="enabling local network discovery"
Feb 11 23:25:44 volumio2 volumio[1193]: info: Starting Shairport Sync
Feb 11 23:25:44 volumio2 volumio5-onboarding[1608]: time=2026-02-11T23:25:44.524+01:00 level=INFO msg="bootstrapping state" hasInternet=true
Feb 11 23:25:44 volumio2 volumio[1193]: info: Starting Shairport Sync
Feb 11 23:25:44 volumio2 volumio[1193]: info: Starting Shairport Sync
Feb 11 23:25:44 volumio2 sudo[1679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 11 23:25:44 volumio2 sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:44 volumio2 sudo[1676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 11 23:25:44 volumio2 sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:44 volumio2 sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 11 23:25:44 volumio2 sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:25:44 volumio2 volumio[1193]: info: Received Get System Info
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 11 23:25:44 volumio2 volumio[1193]: info: Discovery: Getting this device information
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:44 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 11 23:25:44 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 11 23:25:44 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 11 23:25:44 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 11 23:25:44 volumio2 systemd[1]: shairport-sync.service: Consumed 1.647s CPU time.
Feb 11 23:25:44 volumio2 volumio-remote-updater[691]: Test mode enabled
Feb 11 23:25:44 volumio2 volumio-remote-updater[691]: Alpha mode disabled
Feb 11 23:25:44 volumio2 volumio-remote-updater[691]: Alpha legacy test mode disabled
Feb 11 23:25:44 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetState
Feb 11 23:25:44 volumio2 volumio[1193]: info: CorePlayQueue::getTrack 0
Feb 11 23:25:44 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 11 23:25:44 volumio2 sudo[1676]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:44 volumio2 sudo[1679]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:44 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 11 23:25:44 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 11 23:25:44 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 11 23:25:44 volumio2 volumio[1193]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n
\n","title":"Update v4.097","updateavailable":true}
Feb 11 23:25:44 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 11 23:25:44 volumio2 sudo[1677]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:45 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 11 23:25:45 volumio2 volumio[1193]: info: Shairport-Sync Started
Feb 11 23:25:45 volumio2 volumio[1193]: Error adding Membership: Error: addMembership EINVAL
Feb 11 23:25:45 volumio2 volumio[1193]: info: Shairport-Sync Started
Feb 11 23:25:45 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 11 23:25:45 volumio2 volumio[1193]: info: Shairport-Sync Started
Feb 11 23:25:45 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 11 23:25:45 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 11 23:25:45 volumio2 mpd[1628]: 2026-02-11T23:25:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 11 23:25:45 volumio2 systemd[1]: Started mpd.service - Music Player Daemon.
Feb 11 23:25:45 volumio2 sudo[1590]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:45 volumio2 sudo[1604]: pam_unix(sudo:session): session closed for user root
Feb 11 23:25:45 volumio2 volumio[1193]: info: Completed starting Core Plugins
Feb 11 23:25:45 volumio2 volumio[1193]: info: -------------------------------------------
Feb 11 23:25:45 volumio2 volumio[1193]: info: ----- MyVolumio plugins startup ----
Feb 11 23:25:45 volumio2 volumio[1193]: info: -------------------------------------------
Feb 11 23:25:45 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 11 23:25:46 volumio2 volumio[1193]: error: MPD error: The expression evaluated to a falsy value:
Feb 11 23:25:46 volumio2 volumio[1193]: assert.ok(self.idling)
Feb 11 23:25:46 volumio2 volumio[1193]: error: The expression evaluated to a falsy value:
Feb 11 23:25:46 volumio2 volumio[1193]: assert.ok(self.idling)
Feb 11 23:25:46 volumio2 volumio[1193]: info: MPD running with PID1628
Feb 11 23:25:46 volumio2 volumio[1193]: ,establishing connection
Feb 11 23:25:46 volumio2 volumio[1193]: error: MPD error: The expression evaluated to a falsy value:
Feb 11 23:25:46 volumio2 volumio[1193]: assert.ok(self.idling)
Feb 11 23:25:46 volumio2 volumio[1193]: error: The expression evaluated to a falsy value:
Feb 11 23:25:46 volumio2 volumio[1193]: assert.ok(self.idling)
Feb 11 23:25:46 volumio2 volumio[1193]: error: updateQueue error: null
Feb 11 23:25:51 volumio2 volumio[1193]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Feb 11 23:25:52 volumio2 upmpdcli[1725]: writing RSA key
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin multiroom to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 11 23:25:54 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 11 23:25:56 volumio2 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Feb 11 23:25:58 volumio2 volumio[1193]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 11 23:25:58 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 11 23:25:58 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:58 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:25:58 volumio2 volumio[1193]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 11 23:25:58 volumio2 volumio[1193]: info: MyVolumio login type: Token
Feb 11 23:25:58 volumio2 volumio[1193]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 11 23:25:58 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 11 23:26:00 volumio2 systemd[1]: setdatetime-helper.service: Deactivated successfully.
Feb 11 23:26:00 volumio2 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Feb 11 23:26:02 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 11 23:26:02 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 11 23:26:02 volumio2 volumio[1193]: info: Streaming services startup
Feb 11 23:26:02 volumio2 volumio[1193]: info: Starting Streaming Daemon
Feb 11 23:26:02 volumio2 sudo[1773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 11 23:26:02 volumio2 sudo[1773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:02 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 11 23:26:02 volumio2 sudo[1773]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:02 volumio2 volumio[1193]: error: Cannot start Volumio Streaming Daemon
Feb 11 23:26:02 volumio2 volumio[1193]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 11 23:26:02 volumio2 volumio[1193]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 11 23:26:03 volumio2 volumio[1193]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Feb 11 23:26:04 volumio2 volumio[1193]: info: MyVolumio token set successfully
Feb 11 23:26:04 volumio2 volumio[1193]: info: MYVOLUMIO: Adding device
Feb 11 23:26:04 volumio2 volumio[1193]: info: MYVOLUMIO: Evaluating Server
Feb 11 23:26:04 volumio2 volumio[1193]: info: MyVolumio Plan changed: premium
Feb 11 23:26:04 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Feb 11 23:26:04 volumio2 volumio[1193]: info: Removing browser output: myVolumio user plan is not superstar
Feb 11 23:26:04 volumio2 volumio[1193]: info: Removing audio output:
Feb 11 23:26:04 volumio2 volumio[1193]: info: MYVOLUMIO: Adding device
Feb 11 23:26:04 volumio2 volumio[1193]: info: MYVOLUMIO: Evaluating Server
Feb 11 23:26:05 volumio2 volumio[1193]: info: Remote config written successfully
Feb 11 23:26:05 volumio2 volumio[1193]: info: Starting Tunnel 1
Feb 11 23:26:05 volumio2 volumio[1193]: info: Starting Tunnel Connection Checker
Feb 11 23:26:05 volumio2 volumio[1193]: info: Completed starting MyVolumio Plugin
Feb 11 23:26:06 volumio2 volumio[1193]: info: MYVolumio Device enabled
Feb 11 23:26:06 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Feb 11 23:26:06 volumio2 volumio[1193]: info: MyVolumio status changed
Feb 11 23:26:06 volumio2 volumio[1193]: info: Streaming services startup
Feb 11 23:26:06 volumio2 volumio[1193]: info: Starting Streaming Daemon
Feb 11 23:26:06 volumio2 sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 11 23:26:06 volumio2 sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:06 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Feb 11 23:26:06 volumio2 sudo[1832]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:06 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Feb 11 23:26:06 volumio2 volumio[1193]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Feb 11 23:26:06 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Feb 11 23:26:10 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Feb 11 23:26:11 volumio2 sudo[1837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Feb 11 23:26:11 volumio2 sudo[1837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:11 volumio2 sudo[1837]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:11 volumio2 volumio[1193]: info: MRS: MultiRoom plugin initialized
Feb 11 23:26:11 volumio2 volumio[1193]: info: MRS: STOPPING SNAPCLIENT
Feb 11 23:26:11 volumio2 volumio[1193]: info: MRS: Snap server stop
Feb 11 23:26:11 volumio2 sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Feb 11 23:26:11 volumio2 sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:11 volumio2 volumio[1193]: info: MRS: STOPPING volumioStreaming
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Feb 11 23:26:11 volumio2 sudo[1854]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:11 volumio2 sudo[1859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Feb 11 23:26:11 volumio2 sudo[1859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:11 volumio2 sudo[1861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Feb 11 23:26:11 volumio2 sudo[1861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:11 volumio2 sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Feb 11 23:26:11 volumio2 sudo[1859]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:11 volumio2 sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:11 volumio2 sudo[1861]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Feb 11 23:26:11 volumio2 sudo[1856]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Feb 11 23:26:11 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Feb 11 23:26:12 volumio2 volumio[1193]: info: Preparing to generate the ALSA configuration file
Feb 11 23:26:12 volumio2 volumio[1193]: info: Bluetoothremote--- Checking for trusted devices to reconnect...
Feb 11 23:26:12 volumio2 volumio[1193]: info: Bluetoothremote--- Device list cleared and placeholder written.
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 11 23:26:12 volumio2 volumio[1193]: info: Setting Geolocation for MyVolumio to eu11
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: Setting Geolocation for MyVolumio to eu4
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: MRS: Removed streaming files
Feb 11 23:26:12 volumio2 volumio[1193]: info: MRS: volumioStreaming STOPPED
Feb 11 23:26:12 volumio2 volumio[1193]: info: MRS: SNAPSERVER STOPPED
Feb 11 23:26:12 volumio2 volumio[1193]: info: MRS: SNAPCLIENT STOPPED
Feb 11 23:26:12 volumio2 volumio[1193]: error: Cannot start Volumio Streaming Daemon
Feb 11 23:26:12 volumio2 volumio[1193]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 11 23:26:12 volumio2 volumio[1193]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 11 23:26:12 volumio2 volumio[1193]: info: Asound.conf file unchanged, so no further update is needed
Feb 11 23:26:12 volumio2 volumio[1193]: info: Output device has changed, restarting MPD
Feb 11 23:26:12 volumio2 volumio[1193]: info: Output device has changed, restarting Shairport Sync
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:12 volumio2 sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 11 23:26:12 volumio2 sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:12 volumio2 volumio[1193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 11 23:26:12 volumio2 volumio[1193]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Feb 11 23:26:12 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 11 23:26:12 volumio2 sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 11 23:26:12 volumio2 sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:12 volumio2 sudo[1877]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:12 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:26:12 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Feb 11 23:26:12 volumio2 volumio[1193]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Feb 11 23:26:12 volumio2 volumio[1193]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Feb 11 23:26:12 volumio2 dbus-daemon[678]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.21' (uid=1000 pid=1193 comm="/usr/bin/node /volumio/index.js")
Feb 11 23:26:12 volumio2 dbus-daemon[678]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Feb 11 23:26:13 volumio2 volumio[1193]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Feb 11 23:26:13 volumio2 volumio[1193]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/
Feb 11 23:26:13 volumio2 volumio[1193]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Feb 11 23:26:13 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Feb 11 23:26:13 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Feb 11 23:26:13 volumio2 volumio[1193]: info: MRS: Pushing multiroomSync output for this device
Feb 11 23:26:13 volumio2 volumio[1193]: info: MRS: Pushing multiroomSync output
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding audio output:
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding audio output:
Feb 11 23:26:13 volumio2 volumio[1193]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Feb 11 23:26:13 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:26:13 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding METAVOLUMIO REST API Endpoints
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Feb 11 23:26:13 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Feb 11 23:26:13 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Feb 11 23:26:13 volumio2 volumio[1193]: info: Preparing CD Folders
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding CD REST API Endpoints
Feb 11 23:26:13 volumio2 volumio[1193]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Feb 11 23:26:13 volumio2 volumio[1193]: info: Starting UDEV Watcher for CD
Feb 11 23:26:13 volumio2 volumio[1193]: info: Detecting CD presence with UDEV
Feb 11 23:26:13 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Feb 11 23:26:13 volumio2 systemd[1]: mpd.service: Deactivated successfully.
Feb 11 23:26:13 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 11 23:26:13 volumio2 systemd[1]: mpd.service: Consumed 3.071s CPU time.
Feb 11 23:26:13 volumio2 systemd[1]: mpd.socket: Deactivated successfully.
Feb 11 23:26:13 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 11 23:26:13 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 11 23:26:13 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 11 23:26:13 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 11 23:26:13 volumio2 sudo[1897]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 11 23:26:13 volumio2 sudo[1897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 11 23:26:13 volumio2 sudo[1897]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:14 volumio2 mpd[1899]: 2026-02-11T23:26:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 11 23:26:14 volumio2 systemd[1]: Started mpd.service - Music Player Daemon.
Feb 11 23:26:14 volumio2 sudo[1879]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:18 volumio2 volumio[1193]: warn: [cd-plugin] cdspeedctl: device or media not ready
Feb 11 23:26:18 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding inputs REST Endpoints
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Feb 11 23:26:18 volumio2 volumio[1193]: info: Scanning Audio Inputs
Feb 11 23:26:18 volumio2 volumio[1193]: info: Checking against Known Cards name
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding Server instance for streaming
Feb 11 23:26:18 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Feb 11 23:26:18 volumio2 volumio[1193]: error: Hi Res Audio Failed Login: Missing Login Data
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding HIGHRESAUDIO REST API Endpoints
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Feb 11 23:26:18 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Feb 11 23:26:18 volumio2 volumio[1193]: info: Refreshing TIDAL token
Feb 11 23:26:18 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Feb 11 23:26:18 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Feb 11 23:26:18 volumio2 volumio[1193]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Feb 11 23:26:18 volumio2 sudo[1920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Feb 11 23:26:18 volumio2 sudo[1920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:18 volumio2 sudo[1920]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:18 volumio2 volumio[1193]: info: QobuzConnect: Starting Qobuz Connect socket and service
Feb 11 23:26:18 volumio2 volumio[1193]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Feb 11 23:26:18 volumio2 volumio[1193]: info: Stopping AccessToken refresher cron for QOBUZ
Feb 11 23:26:18 volumio2 sudo[1928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Feb 11 23:26:18 volumio2 sudo[1928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:18 volumio2 volumio[1193]: info: AccessToken refresher cron started for QOBUZ
Feb 11 23:26:18 volumio2 volumio[1193]: info: Adding QOBUZ REST API Endpoints
Feb 11 23:26:18 volumio2 sudo[1928]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:18 volumio2 sudo[1930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Feb 11 23:26:18 volumio2 sudo[1930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 11 23:26:18 volumio2 volumio[1193]: info: Updating MyVolumio device info
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:18 volumio2 volumio[1193]: info: Updating MyVolumio device info
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 23:26:18 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 11 23:26:19 volumio2 volumio[1193]: info: MRS: Getting audio outputs on start
Feb 11 23:26:19 volumio2 volumio[1193]: info: MRS: Requesting all other devices output
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 11 23:26:19 volumio2 volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 11 23:26:19 volumio2 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Feb 11 23:26:19 volumio2 sudo[1930]: pam_unix(sudo:session): session closed for user root
Feb 11 23:26:19 volumio2 volumio[1193]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 11 23:26:19 volumio2 volumio[1193]: DBusError: Unit dbus-org.bluez.service not found.
Feb 11 23:26:19 volumio2 volumio[1193]: at _methodReturnHandlers. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:343:27)
Feb 11 23:26:19 volumio2 volumio[1193]: at handleMessage (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:101:11)
Feb 11 23:26:19 volumio2 volumio[1193]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/bus.js:151:9)
Feb 11 23:26:19 volumio2 volumio[1193]: at EventEmitter.emit (node:events:514:28)
Feb 11 23:26:19 volumio2 volumio[1193]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:132:14
Feb 11 23:26:19 volumio2 volumio[1193]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/message.js:65:9)
Feb 11 23:26:19 volumio2 volumio[1193]: at Socket.emit (node:events:514:28)
Feb 11 23:26:19 volumio2 volumio[1193]: at emitReadable_ (node:internal/streams/readable:609:12)
Feb 11 23:26:19 volumio2 volumio[1193]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) {
Feb 11 23:26:19 volumio2 volumio[1193]: type: 'org.freedesktop.systemd1.NoSuchUnit',
Feb 11 23:26:19 volumio2 volumio[1193]: text: 'Unit dbus-org.bluez.service not found.',
Feb 11 23:26:19 volumio2 volumio[1193]: reply: Message {
Feb 11 23:26:19 volumio2 volumio[1193]: type: 3,
Feb 11 23:26:19 volumio2 volumio[1193]: _sent: false,
Feb 11 23:26:19 volumio2 volumio[1193]: _serial: 3,
Feb 11 23:26:19 volumio2 volumio[1193]: path: undefined,
Feb 11 23:26:19 volumio2 volumio[1193]: interface: undefined,
Feb 11 23:26:19 volumio2 volumio[1193]: member: undefined,
Feb 11 23:26:19 volumio2 volumio[1193]: errorName: 'org.freedesktop.systemd1.NoSuchUnit',
Feb 11 23:26:19 volumio2 volumio[1193]: replySerial: 2,
Feb 11 23:26:19 volumio2 volumio[1193]: destination: ':1.21',
Feb 11 23:26:19 volumio2 volumio[1193]: sender: 'org.freedesktop.DBus',
Feb 11 23:26:19 volumio2 volumio[1193]: signature: 's',
Feb 11 23:26:19 volumio2 volumio[1193]: body: [ 'Unit dbus-org.bluez.service not found.' ],
Feb 11 23:26:19 volumio2 volumio[1193]: flags: 1
Feb 11 23:26:19 volumio2 volumio[1193]: }
Feb 11 23:26:19 volumio2 volumio[1193]: }
Feb 11 23:26:19 volumio2 volumio[1193]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 11 23:26:22 volumio2 sudo[1947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 23:25'
Feb 11 23:26:22 volumio2 sudo[1947]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Jan 29 19:15:15 UTC 2026"
VOLUMIO_VERSION="4.095"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="936289d7e6112f7d71b395242f2871e4"