Mar 04 23:44:05 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 23:44:05 volumio volumio[1143]: info: CURURI: playlists Mar 04 23:44:05 volumio volumio[1143]: info: Listing playlists Mar 04 23:44:06 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:06 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 23:44:06 volumio volumio[1143]: info: CURURI: playlists/test Mar 04 23:44:06 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:09 volumio go-librespot[340]: time="2026-03-04T23:44:09+09:00" level=trace msg="sent dealer ping" Mar 04 23:44:09 volumio go-librespot[340]: time="2026-03-04T23:44:09+09:00" level=trace msg="received dealer pong" Mar 04 23:44:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 23:44:12 volumio volumio[1143]: info: CURURI: music-library Mar 04 23:44:12 volumio volumio[1143]: error: Failed LSINFO: null Mar 04 23:44:12 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:15 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 04 23:44:15 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:17 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 23:44:19 volumio volumio[1143]: info: CURURI: music-library Mar 04 23:44:19 volumio volumio[1143]: error: Failed LSINFO: null Mar 04 23:44:19 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:39 volumio go-librespot[340]: time="2026-03-04T23:44:39+09:00" level=trace msg="received accesspoint ping" Mar 04 23:44:39 volumio go-librespot[340]: time="2026-03-04T23:44:39+09:00" level=trace msg="received accesspoint pong ack" Mar 04 23:44:39 volumio go-librespot[340]: time="2026-03-04T23:44:39+09:00" level=trace msg="sent dealer ping" Mar 04 23:44:39 volumio go-librespot[340]: time="2026-03-04T23:44:39+09:00" level=trace msg="received dealer pong" Mar 04 23:44:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Mar 04 23:44:47 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:48 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Mar 04 23:44:48 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:44:50 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:44:50 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:44:55 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 23:44:55 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 23:44:55 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 23:44:55 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 23:44:55 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 04 23:44:55 volumio volumio[1143]: info: Received Get System Version Mar 04 23:44:55 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 23:44:58 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:44:58 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:06 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 23:45:06 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 04 23:45:08 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:08 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:09 volumio go-librespot[340]: time="2026-03-04T23:45:09+09:00" level=trace msg="sent dealer ping" Mar 04 23:45:09 volumio go-librespot[340]: time="2026-03-04T23:45:09+09:00" level=trace msg="received dealer pong" Mar 04 23:45:10 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.11.57 from 192.168.11.25 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 23:45:10 volumio volumio[1143]: info: Received Get System Info Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 23:45:10 volumio volumio[1143]: info: Discovery: Getting this device information Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:10 volumio volumio[1143]: info: Listing playlists Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 23:45:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 23:45:11 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:11 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:11 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 04 23:45:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 23:45:12 volumio volumio[1143]: info: Received Get System Info Mar 04 23:45:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 23:45:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 23:45:12 volumio volumio[1143]: info: Discovery: Getting this device information Mar 04 23:45:12 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 23:45:13 volumio volumio[1143]: info: Received Get System Info Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 23:45:13 volumio volumio[1143]: info: Discovery: Getting this device information Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 23:45:13 volumio volumio[1143]: info: Getting Alsa Cards List without I2S DAC Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 04 23:45:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 23:45:17 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:17 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:17 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 23:45:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 23:45:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 04 23:45:17 volumio sudo[1550]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 04 23:45:17 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:17 volumio sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 04 23:45:17 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:17 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:18 volumio sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 04 23:45:18 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 04 23:45:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 04 23:45:18 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:18 volumio sudo[1567]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 04 23:45:18 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:18 volumio sudo[1567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:18 volumio sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 23:45:18 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:18 volumio sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 23:45:18 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:18 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:18 volumio sudo[1567]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:18 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:18 volumio sudo[1579]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 04 23:45:18 volumio sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 23:45:18 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root Mar 04 23:45:19 volumio volumio[1143]: info: Received Get System Info Mar 04 23:45:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 23:45:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 23:45:19 volumio volumio[1143]: info: Discovery: Getting this device information Mar 04 23:45:19 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 23:45:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 23:45:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 23:45:20 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 04 23:45:20 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:20 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:21 volumio volumio[1143]: info: CoreCommandRouter::volumioGetQueue Mar 04 23:45:21 volumio volumio[1143]: info: CoreStateMachine::getQueue Mar 04 23:45:21 volumio volumio[1143]: info: CorePlayQueue::getQueue Mar 04 23:45:25 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Mar 04 23:45:25 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:25 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:25 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:25 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:25 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:25 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Mar 04 23:45:28 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:28 volumio volumio[1143]: info: Preloading song: volusonic/radio/artist-10 Mar 04 23:45:28 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:28 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:28 volumio volumio[1143]: info: No valid Plugin REST Endpoint Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 23:45:28 volumio volumio[1143]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 23:45:28 volumio volumio[1143]: info: Received Get System Version Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 23:45:28 volumio volumio[1143]: info: Received Get System Info Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 23:45:28 volumio volumio[1143]: info: Discovery: Getting this device information Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 04 23:45:28 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 04 23:45:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 23:45:29 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Mar 04 23:45:30 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-289 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-290 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-291 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-292 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-294 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-293 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-295 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-296 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-297 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-298 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-299 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-300 Mar 04 23:45:30 volumio volumio[1143]: info: Preloading song: volusonic/track/track-303 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-289 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: No valid Plugin REST Endpoint Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-290 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-291 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-292 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-294 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-293 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-295 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-296 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-297 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-298 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-299 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-300 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-303 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:30 volumio volumio[1143]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::ClearQueue Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::stop Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::clearPlayQueue Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::saveQueue Mar 04 23:45:30 volumio volumio[1143]: info: CoreCommandRouter::volumioPushQueue Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::addQueueItems Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::addQueueItems Mar 04 23:45:30 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-289 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-289 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-290 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-290 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-291 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-291 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-292 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-292 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-294 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-294 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-293 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-293 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: CoreCommandRouter::volumioPushQueue Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::saveQueue Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::updateTrackBlock Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::getTrackBlock Mar 04 23:45:30 volumio volumio[1143]: info: CoreCommandRouter::volumioPlay Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::play index 5 Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::addQueueItems Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::addQueueItems Mar 04 23:45:30 volumio volumio[1143]: info: Preload queue cleared Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-295 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-295 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-296 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-296 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-297 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-297 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-298 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-298 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-299 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-299 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-300 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-300 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: Adding Item to queue: volusonic/track/track-303 Mar 04 23:45:30 volumio volumio[1143]: info: Exploding uri volusonic/track/track-303 in service volusonic Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::stop Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::play index undefined Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:30 volumio volumio[1143]: info: CoreStateMachine::startPlaybackTimer Mar 04 23:45:30 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:30 volumio volumio[1143]: info: [1772635530957] ControllerVolusonic::clearAddPlayTrack Mar 04 23:45:30 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand stop took 97 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 04 23:45:31 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand clear took 3 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand load "https://nextcloud.yucloud.f5.si/apps/music/subsonic//rest/stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic" Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 04 23:45:31 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 04 23:45:31 volumio volumio[1143]: error: updateQueue error: null Mar 04 23:45:31 volumio volumio[1143]: info: ------------------------------ 18ms Mar 04 23:45:31 volumio volumio[1143]: info: CoreCommandRouter::volumioPushQueue Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::saveQueue Mar 04 23:45:31 volumio volumio[1143]: info: CoreStateMachine::updateTrackBlock Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::getTrackBlock Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand add "https://nextcloud.yucloud.f5.si/apps/music/subsonic//rest/stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic" Mar 04 23:45:31 volumio volumio[1143]: error: updateQueue error: null Mar 04 23:45:31 volumio volumio[1143]: info: ------------------------------ 772ms Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 04 23:45:31 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand add "https://nextcloud.yucloud.f5.si/apps/music/subsonic//rest/stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic" took 3 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand play Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 04 23:45:31 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 04 23:45:31 volumio volumio[1143]: info: ------------------------------ 4ms Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand play took 4 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: ------------------------------ 3ms Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::getState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand status took 1 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 04 23:45:31 volumio volumio[1143]: info: [1772635531852] ControllerVolusonic::pushState Mar 04 23:45:31 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:31 volumio volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"https://nextcloud.yucloud.f5.si/apps/music/subsonic//rest/stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic","trackType":""} Mar 04 23:45:31 volumio volumio[1143]: verbose: CURRENT POSITION 5 Mar 04 23:45:31 volumio volumio[1143]: info: CoreStateMachine::syncState stateService play Mar 04 23:45:31 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::getState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 04 23:45:31 volumio volumio[1143]: info: [1772635531958] ControllerVolusonic: MPD player state update Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::getState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::getState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 04 23:45:31 volumio volumio[1143]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand status took 9 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::getState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 04 23:45:31 volumio volumio[1143]: info: Mar 04 23:45:31 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 04 23:45:31 volumio volumio[1143]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand status took 17 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::getState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 04 23:45:31 volumio volumio[1143]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand status took 18 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand clearerror took 13 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand status took 10 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 23:45:31 volumio volumio[1143]: info: ControllerMpd::pushState Mar 04 23:45:31 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:31 volumio volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Mar 04 23:45:31 volumio volumio[1143]: info: ------------------------------ 32ms Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand clearerror took 16 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand status took 15 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand clearerror took 13 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 04 23:45:31 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 04 23:45:31 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:31 volumio volumio[1143]: info: [1772635531996] ControllerVolusonic::pushState Mar 04 23:45:31 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:31 volumio volumio[1143]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"https://nextcloud.yucloud.f5.si/apps/music/subsonic//rest/stream.view?id=track-293&format=raw&u=admin&p=enc:4459654e655165664a463348&v=1.10.2&f=json&c=volusonic","trackType":""} Mar 04 23:45:31 volumio volumio[1143]: verbose: CURRENT POSITION 5 Mar 04 23:45:31 volumio volumio[1143]: info: CoreStateMachine::syncState stateService pause Mar 04 23:45:31 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus play Mar 04 23:45:31 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 04 23:45:31 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:31 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 23:45:31 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 04 23:45:32 volumio volumio[1143]: info: ControllerMpd::pushState Mar 04 23:45:32 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 04 23:45:32 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:32 volumio volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Mar 04 23:45:32 volumio volumio[1143]: info: ControllerMpd::pushState Mar 04 23:45:32 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 04 23:45:32 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:32 volumio volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Mar 04 23:45:32 volumio volumio[1143]: info: ------------------------------ 51ms Mar 04 23:45:32 volumio volumio[1143]: info: ------------------------------ 41ms Mar 04 23:45:32 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 04 23:45:32 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 04 23:45:32 volumio volumio[1143]: info: ControllerMpd::pushState Mar 04 23:45:32 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 04 23:45:32 volumio volumio[1143]: info: CorePlayQueue::getTrack 5 Mar 04 23:45:32 volumio volumio[1143]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Mar 04 23:45:32 volumio volumio[1143]: info: ------------------------------ 42ms Mar 04 23:45:32 volumio volumio[1143]: info: CoreCommandRouter::volumioGetQueue Mar 04 23:45:32 volumio volumio[1143]: info: CoreStateMachine::getQueue Mar 04 23:45:32 volumio volumio[1143]: info: CorePlayQueue::getQueue Mar 04 23:45:32 volumio volumio[1143]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 23:45:35 volumio volumio[1143]: info: Adding volusonic/track/track-295 to favourites with specific volusonic method Mar 04 23:45:35 volumio volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 23:45:35 volumio volumio[1143]: TypeError: Cannot read properties of undefined (reading 'then') Mar 04 23:45:35 volumio volumio[1143]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:679:20) Mar 04 23:45:35 volumio volumio[1143]: at Socket.emit (node:events:514:28) Mar 04 23:45:35 volumio volumio[1143]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 04 23:45:35 volumio volumio[1143]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 04 23:45:35 volumio volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 23:45:36 volumio sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 23:44' Mar 04 23:45:36 volumio sudo[1632]: 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 Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"