-- Logs begin at Mon 2025-04-28 05:45:26 CST, end at Wed 2025-04-30 13:36:24 CST. -- Apr 30 13:35:31 volumio volumio[8847]: info: Apr 30 13:35:31 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:31 volumio volumio[8847]: info: Apr 30 13:35:31 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:31 volumio volumio[8847]: info: Apr 30 13:35:31 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand status took 359 milliseconds Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:31 volumio volumio[8847]: info: Apr 30 13:35:31 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:31 volumio volumio[8847]: info: Apr 30 13:35:31 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:31 volumio volumio[8847]: info: Apr 30 13:35:31 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand status took 365 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand status took 362 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand status took 3 milliseconds Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:31 volumio volumio[8847]: info: ------------------------------ 380ms Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand status took 13 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand status took 12 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:31 volumio volumio[8847]: info: ------------------------------ 394ms Apr 30 13:35:31 volumio volumio[8847]: info: ------------------------------ 393ms Apr 30 13:35:31 volumio volumio[8847]: info: ------------------------------ 34ms Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 30 13:35:31 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:31 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:31 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:31 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:31 volumio volumio[8847]: info: ------------------------------ 53ms Apr 30 13:35:31 volumio volumio[8847]: info: ------------------------------ 52ms Apr 30 13:35:43 volumio volumio[8847]: info: Clearing queue after UPNP request Apr 30 13:35:43 volumio volumio[8847]: verbose: UNSET VOLATILE: Service: mpd Apr 30 13:35:43 volumio volumio[8847]: info: Stopping Airplay Playback and sending pause command to client via USR2 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::ClearQueue Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::stPlaybackTimer Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::updateTrackBlock Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrackBlock Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::serviceStop Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::serviceStop Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::stop Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::clearPlayQueue Apr 30 13:35:44 volumio sudo[6832]: volumio : unable to resolve host volumio Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::saveQueue Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio sudo[6832]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushQueue Apr 30 13:35:44 volumio sudo[6832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 9018 Apr 30 13:35:44 volumio sudo[6832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:35:44 volumio sudo[6832]: pam_unix(sudo:session): session closed for user root Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand stop took 51 milliseconds Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:44 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:35:44 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:35:44 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 16ms Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand status took 15 milliseconds Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 9ms Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand status took 8 milliseconds Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 7ms Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand status took 7 milliseconds Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: 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} Apr 30 13:35:44 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: No code Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: 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} Apr 30 13:35:44 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: No code Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: 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} Apr 30 13:35:44 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: No code Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 61ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 54ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 56ms Apr 30 13:35:44 volumio volumio[8847]: info: Shairport-Sync paused with USR2 Apr 30 13:35:44 volumio volumio[8847]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Christine%20Welch/%E4%B8%80%E7%99%BE%E4%B8%87%E4%B8%AA%E5%8F%AF%E8%83%BD/f71c837b-1393-4aa1-bb25-562e2958bc38.jpg' Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces state update: options Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces state update: options Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces state update: options Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand status took 4 milliseconds Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand status took 4 milliseconds Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: 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} Apr 30 13:35:44 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: No code Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: 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} Apr 30 13:35:44 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: No code Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 112ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 113ms Apr 30 13:35:44 volumio volumio[8847]: info: Starting UPNP Playback Apr 30 13:35:44 volumio volumio[8847]: info: Preparing playback through UPNP Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioGetState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 13:35:44 volumio volumio[8847]: info: sendMpdCommand status took 118 milliseconds Apr 30 13:35:44 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:44 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:35:44 volumio volumio[8847]: 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} Apr 30 13:35:44 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: No code Apr 30 13:35:44 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:44 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 145ms Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 12ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 10ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 9ms Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: Apr 30 13:35:44 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:44 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:35:44 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:35:45 volumio volumio[8847]: info: Restarting Shairport-Sync after stop Apr 30 13:35:45 volumio sudo[6891]: volumio : unable to resolve host volumio Apr 30 13:35:45 volumio sudo[6891]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 30 13:35:45 volumio sudo[6891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 13:35:45 volumio sudo[6891]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio shairport-sync[9018]: {"time":1745991252278,"response":"stopAirplayPlayback Success"} Apr 30 13:35:45 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 27 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 20 milliseconds Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:45 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 19 milliseconds Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 17 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 22 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 22 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 23ms Apr 30 13:35:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:45 volumio sudo[6891]: pam_unix(sudo:session): session closed for user root Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:45 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:45 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:45 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:45 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:45 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:45 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:45 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:45 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:45 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:45 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:45 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 139ms Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 168ms Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 157ms Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:45 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:35:45 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:35:45 volumio volumio[8847]: info: Apr 30 13:35:45 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 140 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 136 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 124 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 123 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 17ms Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 13 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 12ms Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand status took 3 milliseconds Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:45 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:45 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:45 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:45 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:45 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:45 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:45 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:45 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:45 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:45 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:45 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:45 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 256ms Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 247ms Apr 30 13:35:45 volumio volumio[8847]: info: ------------------------------ 248ms Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:45 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:45 volumio volumio[8847]: info: Shairport-Sync retarted Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 118 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 118 milliseconds Apr 30 13:35:45 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 121 milliseconds Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:45 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:46 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:35:46 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:46 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:46 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:46 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:46 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:46 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:46 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:46 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":762,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:46 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:46 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:46 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:35:46 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:35:46 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/0e80a3b3ed90aa0061c06ce58aafb252/6811b6ae/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:35:46 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:35:46 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:46 volumio volumio[8847]: info: ------------------------------ 321ms Apr 30 13:35:46 volumio volumio[8847]: info: ------------------------------ 196ms Apr 30 13:35:46 volumio volumio[8847]: info: ------------------------------ 194ms Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:46 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:46 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:35:52 volumio volumio[5778]: 79 Apr 30 13:35:52 volumio volumio[5778]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 13:35:52 volumio volumio[5778]: Dload Upload Total Spent Left Speed Apr 30 13:35:52 volumio volumio[8847]: info: Apr 30 13:35:52 volumio volumio[8847]: ---------------------------- Client requests Volume 79 Apr 30 13:35:52 volumio volumio[8847]: info: VolumeController::SetAlsaVolume79 Apr 30 13:35:52 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:35:52 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:35:52 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:35:52 volumio volumio[5778]: [158B blob data] Apr 30 13:35:52 volumio volumio[5778]: {"time":1745991352694,"response":"volume Success"}79 Apr 30 13:35:52 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:35:52 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:35:52 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:15 volumio volumio[8847]: info: Clearing queue after UPNP request Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::ClearQueue Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::stPlaybackTimer Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::updateTrackBlock Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrackBlock Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::serviceStop Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::stop Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::clearPlayQueue Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::saveQueue Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushQueue Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand stop took 21 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 5 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 3 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 2 milliseconds Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: 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} Apr 30 13:36:16 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: No code Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: 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} Apr 30 13:36:16 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: No code Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: 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} Apr 30 13:36:16 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: No code Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 44ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 43ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 42ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:36:16 volumio volumio[8847]: info: Starting UPNP Playback Apr 30 13:36:16 volumio volumio[8847]: info: Preparing playback through UPNP Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioGetState Apr 30 13:36:16 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 6ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:16 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 17ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 16ms Apr 30 13:36:16 volumio volumio[8847]: info: ------------------------------ 16ms Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 7 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 5 milliseconds Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: Apr 30 13:36:16 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 9 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 8 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 5 milliseconds Apr 30 13:36:16 volumio volumio[8847]: info: sendMpdCommand status took 3 milliseconds Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:16 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:16 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:16 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友 (Performer)","album":null,"uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:16 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:16 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:16 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:16 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友 (Performer)","album":null,"uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:16 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:16 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:16 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 61ms Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 68ms Apr 30 13:36:17 volumio volumio[8847]: info: Apr 30 13:36:17 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:17 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:17 volumio volumio[8847]: info: Apr 30 13:36:17 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 63 milliseconds Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 62 milliseconds Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 69 milliseconds Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 218ms Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 219ms Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 221ms Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 223ms Apr 30 13:36:17 volumio volumio[8847]: info: Apr 30 13:36:17 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:17 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:17 volumio volumio[8847]: info: Apr 30 13:36:17 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:17 volumio volumio[8847]: info: Apr 30 13:36:17 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:17 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:17 volumio volumio[8847]: info: Apr 30 13:36:17 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 275ms Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand status took 279 milliseconds Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 118ms Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand status took 111 milliseconds Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 106ms Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand status took 103 milliseconds Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 13:36:17 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 30 13:36:17 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":910,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:17 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:17 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":910,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只愿一生爱一人","artist":"张学友","album":"金曲精选 1985-1990","uri":"http://er.sycdn.kuwo.cn/7795264faad9b0b1ad56b08f36defe2f/6811b6cf/resource/30106/trackmedia/M500004RS7zx4SmQsj.mp3","trackType":"mp3"} Apr 30 13:36:17 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:17 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 333ms Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 172ms Apr 30 13:36:17 volumio volumio[8847]: info: ------------------------------ 157ms Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:17 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:17 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:21 volumio volumio[8847]: info: Clearing queue after UPNP request Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::ClearQueue Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::stPlaybackTimer Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::updateTrackBlock Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrackBlock Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::serviceStop Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::stop Apr 30 13:36:21 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::clearPlayQueue Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::saveQueue Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushQueue Apr 30 13:36:21 volumio volumio[8847]: info: Apr 30 13:36:21 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:21 volumio volumio[8847]: info: sendMpdCommand stop took 30 milliseconds Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:21 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:21 volumio volumio[8847]: info: Apr 30 13:36:21 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:21 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:21 volumio volumio[8847]: info: Apr 30 13:36:21 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:21 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:21 volumio volumio[8847]: info: Apr 30 13:36:21 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:21 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:21 volumio volumio[8847]: info: sendMpdCommand status took 8 milliseconds Apr 30 13:36:21 volumio volumio[8847]: info: sendMpdCommand status took 7 milliseconds Apr 30 13:36:21 volumio volumio[8847]: info: sendMpdCommand status took 6 milliseconds Apr 30 13:36:21 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: 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} Apr 30 13:36:21 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: No code Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: 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} Apr 30 13:36:21 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: No code Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: 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} Apr 30 13:36:21 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::syncState stateService stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: No code Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:21 volumio volumio[8847]: info: ------------------------------ 60ms Apr 30 13:36:21 volumio volumio[8847]: info: ------------------------------ 63ms Apr 30 13:36:21 volumio volumio[8847]: info: ------------------------------ 61ms Apr 30 13:36:21 volumio volumio[8847]: info: Apr 30 13:36:21 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:21 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:21 volumio volumio[8847]: info: Apr 30 13:36:21 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:21 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:21 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:36:21 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:36:21 volumio volumio[8847]: error: updateQueue error: null Apr 30 13:36:21 volumio volumio[8847]: info: ------------------------------ 68ms Apr 30 13:36:21 volumio volumio[8847]: info: ------------------------------ 9ms Apr 30 13:36:21 volumio volumio[8847]: info: ------------------------------ 8ms Apr 30 13:36:21 volumio volumio[8847]: info: Starting UPNP Playback Apr 30 13:36:21 volumio volumio[8847]: info: Preparing playback through UPNP Apr 30 13:36:21 volumio volumio[8847]: info: CoreCommandRouter::volumioGetState Apr 30 13:36:21 volumio volumio[8847]: info: CorePlayQueue::getTrack 0 Apr 30 13:36:21 volumio volumio[8847]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 6ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 5ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 2ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 4ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 3ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 11 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 15 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 12 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 5 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus stop Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 24ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 11 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 2 milliseconds Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 84ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 86ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 81ms Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD (Performer)","album":null,"uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD","album":"BAAD","uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 149ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 140ms Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces system playlist update Apr 30 13:36:22 volumio volumio[8847]: info: Ignoring MPD Status Update Apr 30 13:36:22 volumio volumio[8847]: info: Apr 30 13:36:22 volumio volumio[8847]: ---------------------------- MPD announces state update: player Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::getState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand status Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 104ms Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 97 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 15ms Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 9 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 9ms Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand status took 8 milliseconds Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseState Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 13:36:22 volumio volumio[8847]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: verbose: ControllerMpd::parseTrackInfo Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD","album":"BAAD","uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD","album":"BAAD","uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ControllerMpd::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::servicePushState Apr 30 13:36:22 volumio volumio[8847]: verbose: In UPNP mode Apr 30 13:36:22 volumio volumio[8847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"君が好きだと叫びたい","artist":"BAAD","album":"BAAD","uri":"http://lw.sycdn.kuwo.cn/c74cf77c26b3e880e802632d0c693816/6811b6d3/resource/30106/trackmedia/M500002LedzT4Cjtgo.mp3","trackType":"mp3"} Apr 30 13:36:22 volumio volumio[8847]: verbose: CURRENT POSITION 0 Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState stateService play Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::syncState currentStatus play Apr 30 13:36:22 volumio volumio[8847]: info: Received an update from plugin. extracting info from payload Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::pushState Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioPushState Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 147ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 55ms Apr 30 13:36:22 volumio volumio[8847]: info: ------------------------------ 54ms Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreCommandRouter::volumioGetQueue Apr 30 13:36:22 volumio volumio[8847]: info: CoreStateMachine::getQueue Apr 30 13:36:22 volumio volumio[8847]: info: CorePlayQueue::getQueue Apr 30 13:36:24 volumio volumio[8847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 13:36:24 volumio volumio[8847]: Error: connect ECONNREFUSED 199.59.148.7:443 Apr 30 13:36:24 volumio volumio[8847]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 30 13:36:24 volumio volumio[8847]: errno: -111, Apr 30 13:36:24 volumio volumio[8847]: code: 'ECONNREFUSED', Apr 30 13:36:24 volumio volumio[8847]: syscall: 'connect', Apr 30 13:36:24 volumio volumio[8847]: address: '199.59.148.7', Apr 30 13:36:24 volumio volumio[8847]: port: 443 Apr 30 13:36:24 volumio volumio[8847]: } Apr 30 13:36:24 volumio volumio[8847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 13:36:24 volumio sudo[7107]: volumio : unable to resolve host volumio Apr 30 13:36:24 volumio sudo[7107]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 30 13:36:24 volumio sudo[7107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 13:35 Apr 30 13:36:24 volumio sudo[7107]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"