-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Wed 2024-06-26 21:26:59 EEST. -- Jun 26 21:25:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:00 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:01 volumio ntpd[809]: Soliciting pool server 91.217.155.60 Jun 26 21:25:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:05 volumio ntpd[809]: Soliciting pool server 2606:4700:f1::1 Jun 26 21:25:05 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:08 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:10 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:12 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 21:25:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 21:25:14 volumio volumio[1009]: info: Discovery: Getting this device information Jun 26 21:25:14 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Jun 26 21:25:14 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand update Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: Jun 26 21:25:15 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand update took 1 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: Jun 26 21:25:15 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: Jun 26 21:25:15 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: Command Router : Notfying DB Updatetrue Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: 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} Jun 26 21:25:15 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: No code Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: 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} Jun 26 21:25:15 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: No code Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: ------------------------------ 108ms Jun 26 21:25:15 volumio volumio[1009]: info: ------------------------------ 108ms Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: Jun 26 21:25:15 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: Jun 26 21:25:15 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 111 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 110 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: 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} Jun 26 21:25:15 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: No code Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: 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} Jun 26 21:25:15 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: No code Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: 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} Jun 26 21:25:15 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: No code Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: ------------------------------ 249ms Jun 26 21:25:15 volumio volumio[1009]: info: ------------------------------ 142ms Jun 26 21:25:15 volumio volumio[1009]: info: ------------------------------ 141ms Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: Jun 26 21:25:15 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 9 milliseconds Jun 26 21:25:15 volumio volumio[1009]: info: sendMpdCommand status took 8 milliseconds Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:15 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: 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} Jun 26 21:25:15 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: No code Jun 26 21:25:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:15 volumio volumio[1009]: info: ------------------------------ 45ms Jun 26 21:25:16 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:16 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand update Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: Jun 26 21:25:18 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand update took 2 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: Jun 26 21:25:18 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: Jun 26 21:25:18 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: Jun 26 21:25:18 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: Command Router : Notfying DB Updatetrue Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: 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} Jun 26 21:25:18 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: No code Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: 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} Jun 26 21:25:18 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: No code Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: 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} Jun 26 21:25:18 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: No code Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: ------------------------------ 108ms Jun 26 21:25:18 volumio volumio[1009]: info: ------------------------------ 106ms Jun 26 21:25:18 volumio volumio[1009]: info: ------------------------------ 106ms Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: Jun 26 21:25:18 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: Jun 26 21:25:18 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 108 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 107 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:18 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:18 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: 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} Jun 26 21:25:18 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: No code Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: 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} Jun 26 21:25:18 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: No code Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: 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} Jun 26 21:25:18 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: No code Jun 26 21:25:18 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:18 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:18 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:18 volumio volumio[1009]: info: ------------------------------ 216ms Jun 26 21:25:18 volumio volumio[1009]: info: ------------------------------ 112ms Jun 26 21:25:18 volumio volumio[1009]: info: ------------------------------ 111ms Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand update Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: Jun 26 21:25:19 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand update took 2 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: Jun 26 21:25:19 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: Jun 26 21:25:19 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: Jun 26 21:25:19 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: 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} Jun 26 21:25:19 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: No code Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: 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} Jun 26 21:25:19 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: No code Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: 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} Jun 26 21:25:19 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: No code Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: ------------------------------ 112ms Jun 26 21:25:19 volumio volumio[1009]: info: ------------------------------ 111ms Jun 26 21:25:19 volumio volumio[1009]: info: ------------------------------ 110ms Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: Jun 26 21:25:19 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: Jun 26 21:25:19 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 114 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 114 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:25:19 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:25:19 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: 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} Jun 26 21:25:19 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: No code Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: 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} Jun 26 21:25:19 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: No code Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: 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} Jun 26 21:25:19 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: No code Jun 26 21:25:19 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:25:19 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:19 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:25:19 volumio volumio[1009]: info: ------------------------------ 231ms Jun 26 21:25:19 volumio volumio[1009]: info: ------------------------------ 121ms Jun 26 21:25:19 volumio volumio[1009]: info: ------------------------------ 120ms Jun 26 21:25:20 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:21 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:24 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:24 volumio ntpd[809]: 162.159.200.123 local addr 192.168.1.81 -> Jun 26 21:25:26 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:31 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:32 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:36 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:36 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:40 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:42 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 21:25:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 21:25:44 volumio volumio[1009]: info: Discovery: Getting this device information Jun 26 21:25:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Jun 26 21:25:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:25:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 21:25:47 volumio ntpd[809]: Soliciting pool server 193.93.164.195 Jun 26 21:25:47 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:48 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:48 volumio ntpd[809]: Soliciting pool server 193.239.214.226 Jun 26 21:25:52 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:52 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:25:56 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:25:57 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:02 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:08 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:08 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:11 volumio ntpd[809]: 83.212.109.72 local addr 192.168.1.81 -> Jun 26 21:26:12 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:13 volumio ntpd[809]: Soliciting pool server 2606:4700:f1::123 Jun 26 21:26:13 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 21:26:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 21:26:14 volumio volumio[1009]: info: Discovery: Getting this device information Jun 26 21:26:14 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Jun 26 21:26:14 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:14 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 21:26:16 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:18 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:19 volumio ntpd[809]: 62.1.105.163 local addr 192.168.1.81 -> Jun 26 21:26:20 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:23 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:24 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 26 21:26:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 26 21:26:28 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:28 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand update Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: Jun 26 21:26:32 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand update took 2 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: Jun 26 21:26:32 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: Jun 26 21:26:32 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: Jun 26 21:26:32 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: Command Router : Notfying DB Updatetrue Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: 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} Jun 26 21:26:32 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: No code Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: 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} Jun 26 21:26:32 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: No code Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: 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} Jun 26 21:26:32 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: No code Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: ------------------------------ 103ms Jun 26 21:26:32 volumio volumio[1009]: info: ------------------------------ 102ms Jun 26 21:26:32 volumio volumio[1009]: info: ------------------------------ 101ms Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: Jun 26 21:26:32 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: Jun 26 21:26:32 volumio volumio[1009]: ---------------------------- MPD announces state update: update Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::getState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 103 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 102 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 4 milliseconds Jun 26 21:26:32 volumio volumio[1009]: info: sendMpdCommand status took 5 milliseconds Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: Command Router : Notfying DB Updatefalse Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent Jun 26 21:26:32 volumio volumio[1009]: verbose: ControllerMpd::parseState Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: 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} Jun 26 21:26:32 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: No code Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: 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} Jun 26 21:26:32 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: No code Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: ControllerMpd::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: 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} Jun 26 21:26:32 volumio volumio[1009]: verbose: CURRENT POSITION 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:32 volumio volumio[1009]: info: No code Jun 26 21:26:32 volumio volumio[1009]: info: CoreStateMachine::pushState Jun 26 21:26:32 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:32 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jun 26 21:26:33 volumio volumio[1009]: info: ------------------------------ 199ms Jun 26 21:26:33 volumio volumio[1009]: info: ------------------------------ 99ms Jun 26 21:26:33 volumio volumio[1009]: info: ------------------------------ 98ms Jun 26 21:26:34 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:36 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:39 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:40 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 26 21:26:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 26 21:26:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 26 21:26:44 volumio volumio[1009]: info: Discovery: Getting this device information Jun 26 21:26:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState Jun 26 21:26:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 Jun 26 21:26:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 26 21:26:44 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 26 21:26:47 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 26 21:26:49 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:54 volumio ntpd[809]: Soliciting pool server 193.93.164.194 Jun 26 21:26:54 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -110) Jun 26 21:26:55 volumio ntpd[809]: Soliciting pool server 193.239.214.226 Jun 26 21:26:59 volumio volumio[1009]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 21:26:59 volumio volumio[1009]: Error: certificate is not yet valid Jun 26 21:26:59 volumio volumio[1009]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jun 26 21:26:59 volumio volumio[1009]: at TLSSocket.emit (events.js:315:20) Jun 26 21:26:59 volumio volumio[1009]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jun 26 21:26:59 volumio volumio[1009]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jun 26 21:26:59 volumio volumio[1009]: code: 'CERT_NOT_YET_VALID' Jun 26 21:26:59 volumio volumio[1009]: } Jun 26 21:26:59 volumio volumio[1009]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 21:26:59 volumio sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-26 21:25 Jun 26 21:26:59 volumio sudo[2225]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"