-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sun 2025-01-19 15:03:01 CST. -- Jan 19 15:02:03 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:07 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:11 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:15 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:19 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:23 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:24 volumio volumio[1056]: info: Enabling MyMusic plugin smart_inputs Jan 19 15:02:24 volumio volumio[1056]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service smart_inputs Jan 19 15:02:24 volumio volumio[1056]: info: [MyVolumio PluginManager] Plugin music_service smart_inputs not in user plan, not enabling Jan 19 15:02:24 volumio volumio[1056]: info: Error: Plugin music_service smart_inputs not in user plan, not enabling Jan 19 15:02:24 volumio volumio[1056]: error: Could not Enable MyMusic Plugin: Error Jan 19 15:02:27 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:31 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:35 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:39 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:43 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand rescan Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:44 volumio volumio[1056]: info: Jan 19 15:02:44 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand rescan took 13 milliseconds Jan 19 15:02:44 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand status took 10 milliseconds Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand status took 3 milliseconds Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:44 volumio volumio[1056]: info: Command Router : Notfying DB Updatetrue Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:44 volumio volumio[1056]: info: MPD Database updated - AlbumList cache refreshed Jan 19 15:02:44 volumio volumio[1056]: info: Jan 19 15:02:44 volumio volumio[1056]: ---------------------------- MPD announces state update: database Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:44 volumio volumio[1056]: info: Jan 19 15:02:44 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:44 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:44 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:44 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:44 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: No code Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: ------------------------------ 214ms Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand status took 106 milliseconds Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand status took 103 milliseconds Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand status took 102 milliseconds Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:44 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:02:44 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:44 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:44 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:44 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: No code Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:44 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: No code Jan 19 15:02:44 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:44 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:44 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:44 volumio volumio[1056]: info: ------------------------------ 272ms Jan 19 15:02:44 volumio volumio[1056]: info: ------------------------------ 273ms Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:47 volumio volumio[1056]: info: Jan 19 15:02:47 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand update took 8 milliseconds Jan 19 15:02:47 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:47 volumio volumio[1056]: info: Jan 19 15:02:47 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:47 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand status took 8 milliseconds Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand status took 6 milliseconds Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:47 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand status took 83 milliseconds Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand status took 81 milliseconds Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:47 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:47 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:47 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:47 volumio volumio[1056]: info: No code Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:47 volumio volumio[1056]: info: ------------------------------ 207ms Jan 19 15:02:47 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 94 milliseconds Jan 19 15:02:47 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:47 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:47 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:47 volumio volumio[1056]: info: No code Jan 19 15:02:47 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:47 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:47 volumio volumio[1056]: info: ------------------------------ 292ms Jan 19 15:02:47 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: info: Jan 19 15:02:48 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: info: Jan 19 15:02:48 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:48 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 8 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 5 milliseconds Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:48 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 63 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 62 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:48 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:48 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:48 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:48 volumio volumio[1056]: info: No code Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:48 volumio volumio[1056]: info: ------------------------------ 183ms Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 92 milliseconds Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:48 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:48 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:48 volumio volumio[1056]: info: No code Jan 19 15:02:48 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:48 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:48 volumio volumio[1056]: info: ------------------------------ 266ms Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: info: Jan 19 15:02:48 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: info: Jan 19 15:02:48 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:48 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 8 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 5 milliseconds Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:48 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:48 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 63 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand status took 61 milliseconds Jan 19 15:02:48 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:02:48 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:49 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: No code Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: ------------------------------ 207ms Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 105 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:49 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: No code Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: ------------------------------ 290ms Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: Jan 19 15:02:49 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: Jan 19 15:02:49 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 5 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 63 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 62 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:49 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: No code Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: ------------------------------ 183ms Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 91 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:49 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: No code Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: ------------------------------ 264ms Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: Jan 19 15:02:49 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand update took 9 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: Jan 19 15:02:49 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 12 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 6 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 4 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 62 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:49 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: No code Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:49 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: No code Jan 19 15:02:49 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:49 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:49 volumio volumio[1056]: info: ------------------------------ 278ms Jan 19 15:02:49 volumio volumio[1056]: info: ------------------------------ 272ms Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: Jan 19 15:02:49 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: Jan 19 15:02:49 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:49 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 8 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 5 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:49 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 62 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand status took 61 milliseconds Jan 19 15:02:49 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:02:49 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:50 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:50 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:50 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:50 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:50 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:50 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:50 volumio volumio[1056]: info: No code Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:50 volumio volumio[1056]: info: ------------------------------ 178ms Jan 19 15:02:50 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 89 milliseconds Jan 19 15:02:50 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:50 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:50 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:50 volumio volumio[1056]: info: No code Jan 19 15:02:50 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:50 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:50 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:50 volumio volumio[1056]: info: ------------------------------ 254ms Jan 19 15:02:51 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:55 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand update Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:58 volumio volumio[1056]: info: Jan 19 15:02:58 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand update took 7 milliseconds Jan 19 15:02:58 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:58 volumio volumio[1056]: info: Jan 19 15:02:58 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:02:58 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand status took 5 milliseconds Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:58 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand status took 66 milliseconds Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand status took 64 milliseconds Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:58 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:58 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:58 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:58 volumio volumio[1056]: info: No code Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:58 volumio volumio[1056]: info: ------------------------------ 183ms Jan 19 15:02:58 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 85 milliseconds Jan 19 15:02:58 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:02:58 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:02:58 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:58 volumio volumio[1056]: info: No code Jan 19 15:02:58 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:02:58 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:02:58 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:02:58 volumio volumio[1056]: info: ------------------------------ 280ms Jan 19 15:02:59 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand rescan Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:03:00 volumio volumio[1056]: info: Jan 19 15:03:00 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand rescan took 6 milliseconds Jan 19 15:03:00 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:03:00 volumio volumio[1056]: info: Jan 19 15:03:00 volumio volumio[1056]: ---------------------------- MPD announces state update: update Jan 19 15:03:00 volumio volumio[1056]: info: ControllerMpd::getState Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand status took 7 milliseconds Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand status took 5 milliseconds Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:03:00 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand status took 115 milliseconds Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand status took 114 milliseconds Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:03:00 volumio volumio[1056]: info: Command Router : Notfying DB Updatefalse Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::Close All Modals sent Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::parseState Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:03:00 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:03:00 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:03:00 volumio volumio[1056]: info: No code Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:03:00 volumio volumio[1056]: info: ------------------------------ 232ms Jan 19 15:03:00 volumio volumio[1056]: info: sendMpdCommand playlistinfo took 83 milliseconds Jan 19 15:03:00 volumio volumio[1056]: verbose: ControllerMpd::parseTrackInfo Jan 19 15:03:00 volumio volumio[1056]: info: ControllerMpd::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::servicePushState Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jan 19 15:03:00 volumio volumio[1056]: verbose: CURRENT POSITION 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::syncState stateService stop Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:03:00 volumio volumio[1056]: info: No code Jan 19 15:03:00 volumio volumio[1056]: info: CoreStateMachine::pushState Jan 19 15:03:00 volumio volumio[1056]: info: CorePlayQueue::getTrack 0 Jan 19 15:03:00 volumio volumio[1056]: info: CoreCommandRouter::volumioPushState Jan 19 15:03:00 volumio volumio[1056]: info: ------------------------------ 307ms Jan 19 15:03:00 volumio volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 15:03:00 volumio volumio[1056]: Error: connect ETIMEDOUT 199.16.156.75:80 Jan 19 15:03:00 volumio volumio[1056]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 19 15:03:00 volumio volumio[1056]: errno: -110, Jan 19 15:03:00 volumio volumio[1056]: code: 'ETIMEDOUT', Jan 19 15:03:00 volumio volumio[1056]: syscall: 'connect', Jan 19 15:03:00 volumio volumio[1056]: address: '199.16.156.75', Jan 19 15:03:00 volumio volumio[1056]: port: 80 Jan 19 15:03:00 volumio volumio[1056]: } Jan 19 15:03:00 volumio volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 15:03:01 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 15:02 Jan 19 15:03:01 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"