Jan 27 18:59:11 volumio vtcs[32556]: [2026-01-27 18:59:11] [info] asio async_shutdown error: asio.misc:2 (End of file) Jan 27 18:59:32 volumio volumio[1172]: info: Pushing metadata Jan 27 18:59:32 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Jan 27 18:59:32 volumio volumio[1172]: info: CoreStateMachine::pushState Jan 27 18:59:32 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Jan 27 18:59:32 volumio volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jan 27 18:59:32 volumio volumio[1172]: info: MRS: Pushing multiroomSync output Jan 27 18:59:32 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 18:59:32 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Jan 27 18:59:32 volumio volumio[1172]: info: CoreStateMachine::pushState Jan 27 18:59:32 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Jan 27 18:59:32 volumio volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jan 27 18:59:32 volumio volumio[1172]: info: MRS: Pushing multiroomSync output Jan 27 18:59:32 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 18:59:32 volumio volumio[1172]: info: FusionDsp - Volumio is not playing Jan 27 18:59:32 volumio volumio[1172]: info: FusionDsp - Clipped samples monitor stopped Jan 27 18:59:32 volumio volumio[1172]: info: FusionDsp - Volumio is not playing Jan 27 18:59:32 volumio volumio[1172]: info: FusionDsp - Clipped samples monitor stopped Jan 27 19:00:14 volumio volumio[1172]: verbose: New Socket.io Connection to 192.168.2.188:3000 from 192.168.2.105 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:14 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:14 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 27 19:00:14 volumio sudo[10068]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 27 19:00:14 volumio sudo[10068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:14 volumio sudo[10068]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:14 volumio sudo[10074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 27 19:00:14 volumio sudo[10074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:14 volumio sudo[10074]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:14 volumio sudo[10081]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 27 19:00:14 volumio sudo[10081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:14 volumio sudo[10081]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:14 volumio sudo[10087]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 27 19:00:14 volumio sudo[10087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:14 volumio sudo[10094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 19:00:14 volumio sudo[10087]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:14 volumio sudo[10094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:14 volumio sudo[10094]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:14 volumio sudo[10096]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 19:00:14 volumio sudo[10096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:14 volumio sudo[10096]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:14 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:14 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:14 volumio volumio[1172]: verbose: New Socket.io Connection to 192.168.2.188:3000 from 192.168.2.105 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:14 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:14 volumio volumio[1172]: verbose: New Socket.io Connection to 192.168.2.188:3000 from 192.168.2.105 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 19:00:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 19:00:16 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 19:00:16 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 19:00:17 volumio sudo[10101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 19:00:17 volumio sudo[10101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:17 volumio sudo[10101]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:17 volumio sudo[10103]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 19:00:18 volumio sudo[10103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:18 volumio sudo[10103]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:18 volumio volumio[1172]: verbose: New Socket.io Connection to 192.168.2.188 from 192.168.2.105 UA: Mozilla/5.0 (Linux; Android 13; 2201116PG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 27 19:00:18 volumio sudo[10108]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 19:00:18 volumio sudo[10108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:18 volumio sudo[10108]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:18 volumio sudo[10110]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 19:00:18 volumio sudo[10110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:18 volumio sudo[10110]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:18 volumio volumio[1172]: verbose: New Socket.io Connection to 192.168.2.188 from 192.168.2.105 UA: Mozilla/5.0 (Linux; Android 13; 2201116PG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.127 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 27 19:00:18 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::volumioGetVisibleSources Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 27 19:00:19 volumio volumio[1172]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 27 19:00:19 volumio volumio[1172]: info: Received Get System Info Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:19 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:19 volumio volumio[1172]: info: Listing playlists Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 27 19:00:19 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:20 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 19:00:20 volumio volumio[1172]: info: Received Get System Info Jan 27 19:00:20 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:20 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:20 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:20 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:20 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:20 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 27 19:00:21 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 19:00:21 volumio volumio[1172]: info: Received Get System Info Jan 27 19:00:21 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:21 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:21 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:21 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:21 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:24 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:24 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:24 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:24 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:24 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:24 volumio volumio[1172]: verbose: New Socket.io Connection to 192.168.2.188:3000 from 192.168.2.105 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 27 19:00:24 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 19:00:24 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 19:00:28 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:33 volumio volumio[1172]: info: Retrieving Cloud Streaming UI Jan 27 19:00:33 volumio volumio[1172]: info: Getting Tidal Cloud Configuration Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:33 volumio volumio[1172]: info: Getting Qobuz Cloud Configuration Jan 27 19:00:33 volumio volumio[1172]: info: Asking plugin for UI Config Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:33 volumio volumio[1172]: info: Getting Spotify Cloud Configuration Jan 27 19:00:33 volumio volumio[1172]: info: Asking plugin for UI Config Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:33 volumio volumio[1172]: info: Saving Spotify Acccount Jan 27 19:00:33 volumio volumio[1172]: info: Got it Jan 27 19:00:33 volumio volumio[1172]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 27 19:00:33 volumio volumio[1172]: info: Got Tidal Cloud Configuration Jan 27 19:00:33 volumio volumio[1172]: info: Got it Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 19:00:33 volumio volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 19:00:34 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 19:00:34 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 19:00:36 volumio volumio[1172]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 27 19:00:36 volumio volumio[1172]: info: Received Get System Version Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 19:00:36 volumio volumio[1172]: info: Received Get System Info Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:00:36 volumio volumio[1172]: info: Discovery: Getting this device information Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Jan 27 19:00:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:00:38 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 19:00:39 volumio volumio[1172]: info: Enabling MyMusic plugin airplay_emulation Jan 27 19:00:39 volumio volumio[1172]: info: Enabling plugin airplay_emulation Jan 27 19:00:39 volumio volumio[1172]: info: Loading plugin "airplay_emulation"... Jan 27 19:00:39 volumio volumio[1172]: info: Starting Shairport Sync Jan 27 19:00:39 volumio volumio[1172]: info: PLUGIN START: airplay_emulation Jan 27 19:00:39 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:00:39 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:00:39 volumio volumio[1172]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:00:39 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:00:39 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:00:39 volumio volumio[1172]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:00:39 volumio volumio[1172]: info: Done. Jan 27 19:00:39 volumio volumio[1172]: info: Starting Shairport Sync Jan 27 19:00:39 volumio volumio[1172]: info: Starting Shairport Sync Jan 27 19:00:39 volumio sudo[10180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 19:00:39 volumio sudo[10180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:39 volumio sudo[10182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 19:00:39 volumio sudo[10182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 27 19:00:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 27 19:00:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 19:00:39 volumio systemd[1]: shairport-sync.service: Consumed 53.953s CPU time. Jan 27 19:00:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 19:00:40 volumio sudo[10180]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:40 volumio sudo[10182]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:40 volumio volumio[1172]: info: Shairport-Sync Started Jan 27 19:00:40 volumio volumio[1172]: info: Shairport-Sync Started Jan 27 19:00:42 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 19:00:46 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 19:00:48 volumio volumio[1172]: info: Enabling MyMusic plugin bluetooth Jan 27 19:00:48 volumio volumio[1172]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Jan 27 19:00:48 volumio volumio[1172]: info: Enabling plugin bluetooth Jan 27 19:00:48 volumio volumio[1172]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 27 19:00:48 volumio volumio[1172]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jan 27 19:00:48 volumio volumio[1172]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jan 27 19:00:48 volumio volumio[1172]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jan 27 19:00:48 volumio volumio[1172]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jan 27 19:00:48 volumio bluetoothd[747]: Path / reserved for Adv Monitor app :1.82 Jan 27 19:00:48 volumio bluetoothd[747]: Adv Monitor app :1.82 disconnected from D-Bus Jan 27 19:00:48 volumio volumio[1172]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jan 27 19:00:48 volumio sudo[10204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 27 19:00:48 volumio sudo[10204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:48 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jan 27 19:00:48 volumio sudo[10204]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:48 volumio volumio[1172]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jan 27 19:00:48 volumio volumio[1172]: info: CoreCommandRouter::volumioStop Jan 27 19:00:48 volumio volumio[1172]: info: CoreStateMachine::stop Jan 27 19:00:48 volumio volumio[1172]: info: CoreStateMachine::serviceStop Jan 27 19:00:48 volumio volumio[1172]: info: CoreCommandRouter::serviceStop Jan 27 19:00:48 volumio volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 19:00:48 volumio vtcs[32556]: [close:85] Entering Jan 27 19:00:48 volumio vtcs[32556]: [close:100] Exiting Jan 27 19:00:48 volumio volumiobt[10207]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jan 27 19:00:48 volumio volumio[1172]: TypeError: Cannot read properties of undefined (reading 'then') Jan 27 19:00:48 volumio volumio[1172]: at /myvolumio/plugins/audio_interface/bluetooth/bluetooth_real:1:12468 Jan 27 19:00:48 volumio volumio[1172]: at ChildProcess.exithandler (node:child_process:413:7) Jan 27 19:00:48 volumio volumio[1172]: at ChildProcess.emit (node:events:514:28) Jan 27 19:00:48 volumio volumio[1172]: at maybeClose (node:internal/child_process:1105:16) Jan 27 19:00:48 volumio volumio[1172]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 27 19:00:48 volumio volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 19:00:48 volumio sudo[10208]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jan 27 19:00:48 volumio sudo[10208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:48 volumio sudo[10208]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:48 volumio sudo[10214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jan 27 19:00:48 volumio sudo[10214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:00:48 volumio sudo[10214]: pam_unix(sudo:session): session closed for user root Jan 27 19:00:48 volumio volumiobt[10224]: INFO [BTSTART] Powering on Bluetooth if needed... Jan 27 19:00:48 volumio vtcs[32556]: [2026-01-27 19:00:48] [info] asio async_shutdown error: asio.misc:2 (End of file) Jan 27 19:00:48 volumio bluetoothd[747]: Adv Monitor app :1.83 disconnected from D-Bus Jan 27 19:00:48 volumio volumiobt[10228]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jan 27 19:00:48 volumio volumiobt[10229]: [176B blob data] Jan 27 19:00:48 volumio volumiobt[10229]: [157B blob data] Jan 27 19:00:48 volumio volumiobt[10229]: [157B blob data] Jan 27 19:00:48 volumio volumiobt[10229]: [157B blob data] Jan 27 19:00:48 volumio volumiobt[10229]: [113B blob data] Jan 27 19:00:48 volumio volumiobt[10229]: [bluetoothctl]> discoverable on Jan 27 19:00:48 volumio volumiobt[10229]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 27 19:00:48 volumio volumiobt[10229]: [bluetoothctl]> pairable on Jan 27 19:00:48 volumio bluetoothd[747]: Path / reserved for Adv Monitor app :1.84 Jan 27 19:00:48 volumio bluetoothd[747]: Adv Monitor app :1.84 disconnected from D-Bus Jan 27 19:00:48 volumio volumiobt[10229]: [bluetoothctl]> Jan 27 19:00:48 volumio volumiobt[10230]: INFO [BTSTART] Registering Bluetooth agent... Jan 27 19:00:48 volumio volumiobt[10231]: [NEW] Media /org/bluez/hci0 Jan 27 19:00:48 volumio volumiobt[10231]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 27 19:00:48 volumio volumiobt[10231]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 27 19:00:48 volumio volumiobt[10231]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 27 19:00:48 volumio bluetoothd[747]: Path / reserved for Adv Monitor app :1.85 Jan 27 19:00:48 volumio bluetoothd[747]: Adv Monitor app :1.85 disconnected from D-Bus Jan 27 19:00:48 volumio volumiobt[10232]: No agent is registered Jan 27 19:00:48 volumio volumiobt[10232]: [NEW] Media /org/bluez/hci0 Jan 27 19:00:48 volumio volumiobt[10232]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jan 27 19:00:48 volumio volumiobt[10232]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jan 27 19:00:48 volumio volumiobt[10232]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jan 27 19:00:48 volumio bluetoothd[747]: Path / reserved for Adv Monitor app :1.86 Jan 27 19:00:48 volumio bluetoothd[747]: Adv Monitor app :1.86 disconnected from D-Bus Jan 27 19:00:48 volumio volumiobt[10233]: INFO [BTSTART] Agent registered successfully. Jan 27 19:00:48 volumio volumiobt[10234]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jan 27 19:00:48 volumio sudo[10251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 18:59' Jan 27 19:00:48 volumio sudo[10251]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"