-- Logs begin at Sun 2026-01-18 06:42:54 UTC, end at Fri 2026-10-16 14:38:41 UTC. -- Oct 16 14:37:00 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:01 volumio volumio[4850]: info: Enabling MyMusic plugin cd_controller Oct 16 14:37:01 volumio volumio[4850]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service cd_controller Oct 16 14:37:01 volumio volumio[4850]: info: [MyVolumio PluginManager] Plugin music_service cd_controller not in user plan, not enabling Oct 16 14:37:01 volumio volumio[4850]: info: Error: Plugin music_service cd_controller not in user plan, not enabling Oct 16 14:37:01 volumio volumio[4850]: error: Could not Enable MyMusic Plugin: Error Oct 16 14:37:04 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:05 volumio volumio[4850]: info: Enabling MyMusic plugin bluetooth Oct 16 14:37:05 volumio volumio[4850]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Oct 16 14:37:05 volumio volumio[4850]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Oct 16 14:37:05 volumio volumio[4850]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Oct 16 14:37:05 volumio volumio[4850]: error: Could not Enable MyMusic Plugin: Error Oct 16 14:37:08 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:09 volumio volumio[4850]: info: Oct 16 14:37:09 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:09 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:09 volumio volumio[4850]: info: Command Router : Notfying DB Updatetrue Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:09 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:09 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: No code Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: ------------------------------ 99ms Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:09 volumio volumio[4850]: info: Oct 16 14:37:09 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:09 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:09 volumio volumio[4850]: info: Oct 16 14:37:09 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:09 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:09 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:09 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:09 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: No code Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: ------------------------------ 70ms Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:09 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:09 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:09 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:09 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: No code Oct 16 14:37:09 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:09 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:09 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:09 volumio volumio[4850]: info: ------------------------------ 113ms Oct 16 14:37:10 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 16 14:37:11 volumio smbd[5756]: [2026/10/16 14:37:11.597001, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 16 14:37:11 volumio smbd[5756]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 16 14:37:11 volumio smbd[5756]: [2026/10/16 14:37:11.599740, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 16 14:37:11 volumio smbd[5756]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 16 14:37:11 volumio smbd[5756]: [2026/10/16 14:37:11.600055, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 16 14:37:11 volumio smbd[5756]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 16 14:37:11 volumio smbd[5756]: [2026/10/16 14:37:11.610739, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 16 14:37:11 volumio smbd[5756]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 16 14:37:12 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:16 volumio volumio[4850]: info: Oct 16 14:37:16 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:16 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:16 volumio volumio[4850]: info: Oct 16 14:37:16 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:16 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:16 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:16 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:16 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:16 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:16 volumio volumio[4850]: info: No code Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:16 volumio volumio[4850]: info: ------------------------------ 72ms Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:16 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:16 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:16 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:16 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:17 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:17 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:17 volumio volumio[4850]: info: No code Oct 16 14:37:17 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:17 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:17 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:17 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:17 volumio volumio[4850]: info: ------------------------------ 125ms Oct 16 14:37:20 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:24 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:28 volumio volumio[4850]: info: Oct 16 14:37:28 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:28 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:28 volumio volumio[4850]: info: Oct 16 14:37:28 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:28 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:28 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:28 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:28 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:28 volumio volumio[4850]: info: No code Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:28 volumio volumio[4850]: info: ------------------------------ 73ms Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:28 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:28 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:28 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:28 volumio volumio[4850]: info: No code Oct 16 14:37:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:28 volumio volumio[4850]: info: ------------------------------ 122ms Oct 16 14:37:32 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:36 volumio volumio[4850]: info: Oct 16 14:37:36 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:36 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:36 volumio volumio[4850]: info: Oct 16 14:37:36 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:36 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:36 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:36 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:36 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:36 volumio volumio[4850]: info: No code Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:36 volumio volumio[4850]: info: ------------------------------ 75ms Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:36 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:36 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:36 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:36 volumio volumio[4850]: info: No code Oct 16 14:37:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:36 volumio volumio[4850]: info: ------------------------------ 124ms Oct 16 14:37:40 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:44 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:48 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 14:37:50 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 14:37:50 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 14:37:54 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 14:37:54 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 14:37:54 volumio volumio[4850]: info: Discovery: Getting this device information Oct 16 14:37:54 volumio volumio[4850]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:54 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:54 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 16 14:37:54 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 14:37:54 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 16 14:37:55 volumio sudo[5921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 14:37:55 volumio sudo[5921]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:37:55 volumio sudo[5921]: pam_unix(sudo:session): session closed for user root Oct 16 14:37:55 volumio sudo[5923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 14:37:55 volumio sudo[5923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:37:55 volumio sudo[5923]: pam_unix(sudo:session): session closed for user root Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioGetState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 14:37:56 volumio volumio[4850]: info: Listing playlists Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:56 volumio volumio[4850]: info: Oct 16 14:37:56 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:56 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:56 volumio volumio[4850]: info: Oct 16 14:37:56 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:37:56 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:56 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:56 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:56 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:56 volumio volumio[4850]: info: No code Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:56 volumio volumio[4850]: info: ------------------------------ 110ms Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:56 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:37:56 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:37:56 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:37:56 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:56 volumio volumio[4850]: info: No code Oct 16 14:37:56 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:37:56 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:37:56 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:37:56 volumio volumio[4850]: info: ------------------------------ 178ms Oct 16 14:38:00 volumio volumio[4850]: info: CoreCommandRouter::volumioGetQueue Oct 16 14:38:00 volumio volumio[4850]: info: CoreStateMachine::getQueue Oct 16 14:38:00 volumio volumio[4850]: info: CorePlayQueue::getQueue Oct 16 14:38:02 volumio volumio[4850]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 14:38:02 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 14:38:03 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 16 14:38:03 volumio sudo[5957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 16 14:38:03 volumio sudo[5957]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio sudo[5957]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio sudo[5963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 14:38:03 volumio sudo[5963]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio sudo[5963]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio kernel: Error getting the SSID Oct 16 14:38:03 volumio sudo[5973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 14:38:03 volumio sudo[5973]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio sudo[5973]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio kernel: Error getting the SSID Oct 16 14:38:03 volumio sudo[5978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 14:38:03 volumio sudo[5978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio sudo[5978]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio kernel: Error getting the SSID Oct 16 14:38:03 volumio sudo[5985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 14:38:03 volumio sudo[5985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio sudo[5985]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 16 14:38:03 volumio sudo[5988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 14:38:03 volumio sudo[5988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 16 14:38:03 volumio sudo[5988]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio sudo[5992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 14:38:03 volumio sudo[5992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:03 volumio sudo[5992]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:03 volumio volumio[4850]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 16 14:38:03 volumio volumio[4850]: wlan0 Interface doesn't support scanning. Oct 16 14:38:03 volumio volumio[4850]: info: Cannot use regular scanning, forcing with ap-force Oct 16 14:38:03 volumio sudo[5995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 16 14:38:03 volumio sudo[5995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 14:38:07 volumio sudo[5995]: pam_unix(sudo:session): session closed for user root Oct 16 14:38:07 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:16 volumio volumio[4850]: info: Oct 16 14:38:16 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:38:16 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:16 volumio volumio[4850]: info: Oct 16 14:38:16 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:38:16 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:16 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:16 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:38:16 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:16 volumio volumio[4850]: info: No code Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:16 volumio volumio[4850]: info: ------------------------------ 71ms Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:16 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:38:16 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:16 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:38:16 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:16 volumio volumio[4850]: info: No code Oct 16 14:38:16 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:16 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:16 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:16 volumio volumio[4850]: info: ------------------------------ 129ms Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:28 volumio volumio[4850]: info: Oct 16 14:38:28 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:38:28 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:28 volumio volumio[4850]: info: Oct 16 14:38:28 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:38:28 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:28 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:28 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:38:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:28 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:38:28 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:38:28 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:38:28 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:38:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:28 volumio volumio[4850]: info: No code Oct 16 14:38:28 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:28 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:28 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:28 volumio volumio[4850]: info: ------------------------------ 105ms Oct 16 14:38:28 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:29 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:38:29 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:29 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:38:29 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:29 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:29 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:29 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:38:29 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:38:29 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:38:29 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:38:29 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:29 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:29 volumio volumio[4850]: info: No code Oct 16 14:38:29 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:29 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:29 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:29 volumio volumio[4850]: info: ------------------------------ 153ms Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:36 volumio volumio[4850]: info: Oct 16 14:38:36 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:38:36 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:36 volumio volumio[4850]: info: Oct 16 14:38:36 volumio volumio[4850]: ---------------------------- MPD announces state update: update Oct 16 14:38:36 volumio volumio[4850]: info: ControllerMpd::getState Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::sendMpdCommand status Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:36 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:36 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:38:36 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:36 volumio volumio[4850]: info: No code Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:36 volumio volumio[4850]: info: ------------------------------ 83ms Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:36 volumio volumio[4850]: info: Command Router : Notfying DB Updatefalse Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::Close All Modals sent Oct 16 14:38:36 volumio volumio[4850]: verbose: ControllerMpd::parseState Oct 16 14:38:36 volumio volumio[4850]: info: ControllerMpd::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::servicePushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 14:38:36 volumio volumio[4850]: verbose: CURRENT POSITION 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::syncState stateService stop Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::syncState currentStatus stop Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:36 volumio volumio[4850]: info: No code Oct 16 14:38:36 volumio volumio[4850]: info: CoreStateMachine::pushState Oct 16 14:38:36 volumio volumio[4850]: info: CorePlayQueue::getTrack 2 Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::volumioPushState Oct 16 14:38:36 volumio volumio[4850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 14:38:36 volumio volumio[4850]: info: ------------------------------ 134ms Oct 16 14:38:40 volumio volumio[4850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 14:38:40 volumio volumio[4850]: { Error: connect ETIMEDOUT 128.242.250.157:443 Oct 16 14:38:40 volumio volumio[4850]: at Object._errnoException (util.js:1022:11) Oct 16 14:38:40 volumio volumio[4850]: at _exceptionWithHostPort (util.js:1044:20) Oct 16 14:38:40 volumio volumio[4850]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 16 14:38:40 volumio volumio[4850]: code: 'ETIMEDOUT', Oct 16 14:38:40 volumio volumio[4850]: errno: 'ETIMEDOUT', Oct 16 14:38:40 volumio volumio[4850]: syscall: 'connect', Oct 16 14:38:40 volumio volumio[4850]: address: '128.242.250.157', Oct 16 14:38:40 volumio volumio[4850]: port: 443 } Oct 16 14:38:40 volumio volumio[4850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 14:38:41 volumio sudo[6139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 14:37 Oct 16 14:38:41 volumio sudo[6139]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"