Oct 14 15:05:29 volumio-3b volumio[14152]: info: Clearing queue after UPNP request Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::ClearQueue Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::stPlaybackTimer Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::updateTrackBlock Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrackBlock Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::serviceStop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::stop Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::clearPlayQueue Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::saveQueue Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushQueue Oct 14 15:05:29 volumio-3b volumio[14152]: info: Oct 14 15:05:29 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:29 volumio-3b volumio[14152]: info: sendMpdCommand stop took 97 milliseconds Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:29 volumio-3b volumio[14152]: info: Oct 14 15:05:29 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:29 volumio-3b volumio[14152]: info: Oct 14 15:05:29 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:29 volumio-3b volumio[14152]: info: Oct 14 15:05:29 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:29 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:29 volumio-3b volumio[14152]: info: sendMpdCommand status took 7 milliseconds Oct 14 15:05:29 volumio-3b volumio[14152]: info: sendMpdCommand status took 7 milliseconds Oct 14 15:05:29 volumio-3b volumio[14152]: info: sendMpdCommand status took 6 milliseconds Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: No code Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: No code Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 15:05:29 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus stop Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: No code Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:29 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:29 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 293ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 290ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 291ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: error: updateQueue error: null Oct 14 15:05:30 volumio-3b volumio[14152]: error: updateQueue error: null Oct 14 15:05:30 volumio-3b volumio[14152]: error: updateQueue error: null Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 295ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 7ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 5ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 14 15:05:30 volumio-3b volumio[14152]: info: Starting UPNP Playback Oct 14 15:05:30 volumio-3b volumio[14152]: info: Preparing playback through UPNP Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetState Oct 14 15:05:30 volumio-3b volumio[14152]: info: CorePlayQueue::getTrack 0 Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 6ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 4ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 2ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 3ms Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand status took 22 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand status took 22 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces state update: player Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::getState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:05:30 volumio-3b volumio[14152]: info: Oct 14 15:05:30 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:30 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand status took 29 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand status took 8 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand status took 5 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: info: sendMpdCommand status took 3 milliseconds Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::parseState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: In UPNP mode Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ONE I LOVE","artist":"meav","album":"惠威试音碟人声","uri":"http://192.168.4.22:55599/content/android/49.flac","trackType":"flac"} Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService play Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus stop Oct 14 15:05:30 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: In UPNP mode Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ONE I LOVE","artist":"meav","album":"惠威试音碟人声","uri":"http://192.168.4.22:55599/content/android/49.flac","trackType":"flac"} Oct 14 15:05:30 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService play Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:05:30 volumio-3b volumio[14152]: info: Received an update from plugin. extracting info from payload Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:30 volumio-3b volumio[14152]: info: ------------------------------ 62ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 103ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: Oct 14 15:05:31 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:31 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 80ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 14 15:05:31 volumio-3b volumio[14152]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 14 15:05:31 volumio-3b volumio[14152]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 14 15:05:31 volumio-3b volumio[14152]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:05:31 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: In UPNP mode Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ONE I LOVE","artist":"meav","album":"惠威试音碟人声","uri":"http://192.168.4.22:55599/content/android/49.flac","trackType":"flac"} Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService play Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:05:31 volumio-3b volumio[14152]: info: Received an update from plugin. extracting info from payload Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: In UPNP mode Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"ONE I LOVE","artist":"meav","album":"惠威试音碟人声","uri":"http://192.168.4.22:55599/content/android/49.flac","trackType":"flac"} Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService play Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:05:31 volumio-3b volumio[14152]: info: Received an update from plugin. extracting info from payload Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: In UPNP mode Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"ONE I LOVE","artist":"meav","album":"惠威试音碟人声","uri":"http://192.168.4.22:55599/content/android/49.flac","trackType":"flac"} Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService play Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:05:31 volumio-3b volumio[14152]: info: Received an update from plugin. extracting info from payload Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: ControllerMpd::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::servicePushState Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: In UPNP mode Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"ONE I LOVE","artist":"meav","album":"惠威试音碟人声","uri":"http://192.168.4.22:55599/content/android/49.flac","trackType":"flac"} Oct 14 15:05:31 volumio-3b volumio[14152]: verbose: CURRENT POSITION 0 Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState stateService play Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:05:31 volumio-3b volumio[14152]: info: Received an update from plugin. extracting info from payload Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::pushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioPushState Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 383ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 366ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 360ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 359ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: Oct 14 15:05:31 volumio-3b volumio[14152]: ---------------------------- MPD announces system playlist update Oct 14 15:05:31 volumio-3b volumio[14152]: info: Ignoring MPD Status Update Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CoreStateMachine::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: CorePlayQueue::getQueue Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 380ms Oct 14 15:05:31 volumio-3b volumio[14152]: info: ------------------------------ 97ms Oct 14 15:06:37 volumio-3b volumio[14152]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 15:06:37 volumio-3b volumio[14152]: Error: connect ETIMEDOUT 43.226.16.8:443 Oct 14 15:06:37 volumio-3b volumio[14152]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 14 15:06:37 volumio-3b volumio[14152]: errno: -110, Oct 14 15:06:37 volumio-3b volumio[14152]: code: 'ETIMEDOUT', Oct 14 15:06:37 volumio-3b volumio[14152]: syscall: 'connect', Oct 14 15:06:37 volumio-3b volumio[14152]: address: '43.226.16.8', Oct 14 15:06:37 volumio-3b volumio[14152]: port: 443 Oct 14 15:06:37 volumio-3b volumio[14152]: } Oct 14 15:06:37 volumio-3b volumio[14152]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 15:06:38 volumio-3b sudo[18013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 15:05' Oct 14 15:06:38 volumio-3b sudo[18013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"