-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Wed 2024-09-04 20:48:41 CST. -- Sep 04 20:47:03 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:07 kmusic sudo[8700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 20:47:07 kmusic sudo[8700]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 20:47:07 kmusic sudo[8700]: pam_unix(sudo:session): session closed for user root Sep 04 20:47:07 kmusic sudo[8702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 20:47:07 kmusic sudo[8702]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 20:47:07 kmusic sudo[8702]: pam_unix(sudo:session): session closed for user root Sep 04 20:47:07 kmusic sudo[8706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 04 20:47:07 kmusic sudo[8706]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 20:47:07 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:07 kmusic systemd[1]: Started UPnP Renderer front-end to MPD. Sep 04 20:47:07 kmusic sudo[8706]: pam_unix(sudo:session): session closed for user root Sep 04 20:47:07 kmusic volumio[719]: info: Upmpdcli Daemon Started Sep 04 20:47:07 kmusic volumio[8711]: writing RSA key Sep 04 20:47:08 kmusic volumio[719]: info: MyVolumio login type: Token Sep 04 20:47:11 kmusic volumio[719]: info: Clearing queue after UPNP request Sep 04 20:47:11 kmusic volumio[719]: verbose: UNSET VOLATILE: Service: airplay_emulation Sep 04 20:47:11 kmusic volumio[719]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:11 kmusic sudo[8764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 8443 Sep 04 20:47:11 kmusic sudo[8764]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 20:47:11 kmusic sudo[8764]: pam_unix(sudo:session): session closed for user root Sep 04 20:47:11 kmusic volumio[719]: info: Shairport-Sync paused with USR2 Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::ClearQueue Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::stop Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::stPlaybackTimer Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::updateTrackBlock Sep 04 20:47:11 kmusic volumio[719]: info: CorePlayQueue::getTrackBlock Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:11 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::serviceStop Sep 04 20:47:11 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::serviceStop Sep 04 20:47:11 kmusic volumio[719]: info: ControllerMpd::stop Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 20:47:11 kmusic volumio[719]: info: CorePlayQueue::clearPlayQueue Sep 04 20:47:11 kmusic volumio[719]: info: CorePlayQueue::saveQueue Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::volumioPushQueue Sep 04 20:47:11 kmusic volumio[719]: info: Sep 04 20:47:11 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:11 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:11 kmusic volumio[719]: info: sendMpdCommand stop took 23 milliseconds Sep 04 20:47:11 kmusic volumio[719]: error: updateQueue error: null Sep 04 20:47:11 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:11 kmusic volumio[719]: info: Sep 04 20:47:11 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:11 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:11 kmusic volumio[719]: info: Sep 04 20:47:11 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:11 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:11 kmusic volumio[719]: error: updateQueue error: null Sep 04 20:47:11 kmusic volumio[719]: error: updateQueue error: null Sep 04 20:47:11 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:11 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:11 kmusic volumio[719]: info: Sep 04 20:47:11 kmusic volumio[719]: ---------------------------- MPD announces state update: options Sep 04 20:47:11 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:11 kmusic volumio[719]: info: Sep 04 20:47:11 kmusic volumio[719]: ---------------------------- MPD announces state update: options Sep 04 20:47:11 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:11 kmusic volumio[719]: info: Sep 04 20:47:11 kmusic volumio[719]: ---------------------------- MPD announces state update: options Sep 04 20:47:11 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:11 kmusic volumio[719]: info: Starting UPNP Playback Sep 04 20:47:11 kmusic volumio[719]: info: Preparing playback through UPNP Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::volumioGetState Sep 04 20:47:11 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 20:47:11 kmusic volumio[719]: info: sendMpdCommand status took 11 milliseconds Sep 04 20:47:11 kmusic volumio[719]: info: sendMpdCommand status took 9 milliseconds Sep 04 20:47:11 kmusic volumio[719]: info: sendMpdCommand status took 7 milliseconds Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:11 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:11 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:11 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:11 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:12 kmusic volumio[719]: 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} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService stop Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: No code Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:12 kmusic volumio[719]: 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} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService stop Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: No code Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:12 kmusic volumio[719]: 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} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService stop Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: No code Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 62ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 61ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 61ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 3ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand status took 12 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand status took 11 milliseconds Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand status took 24 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand status took 19 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand status took 12 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand status took 9 milliseconds Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:12 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:12 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:12 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 77ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 96ms Sep 04 20:47:12 kmusic volumio[719]: info: Restarting Shairport-Sync after stop Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 122 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 124 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 121 milliseconds Sep 04 20:47:12 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 121 milliseconds Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:12 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:12 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:12 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:12 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:12 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:12 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:12 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:12 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:12 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:12 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:12 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:12 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 298ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 297ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 288ms Sep 04 20:47:12 kmusic volumio[719]: info: ------------------------------ 287ms Sep 04 20:47:12 kmusic volumio[719]: info: Sep 04 20:47:12 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:12 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:13 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:13 kmusic sudo[8781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 20:47:13 kmusic sudo[8781]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 20:47:13 kmusic volumio[719]: info: Clearing queue after UPNP request Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 276ms Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand status took 271 milliseconds Sep 04 20:47:13 kmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 121ms Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand status took 98 milliseconds Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 97ms Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand status took 94 milliseconds Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:13 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:13 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"690 Kbps","isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:13 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:13 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:13 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:13 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"734 Kbps","isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:13 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:13 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:13 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:13 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"734 Kbps","isStreaming":false,"title":"寂寞在唱歌","artist":"阿桑","album":"寂寞在唱歌","uri":"http://10.0.0.9:5005/music/%E9%98%BF%E6%A1%91%20-%20%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C.flac","trackType":"flac"} Sep 04 20:47:13 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:13 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 386ms Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 232ms Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 208ms Sep 04 20:47:13 kmusic volumio[719]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%98%BF%E6%A1%91/%E5%AF%82%E5%AF%9E%E5%9C%A8%E5%94%B1%E6%AD%8C/edc64ab3-48c7-4776-8a6b-2e9a8752d8f7.jpg' Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic shairport-sync[8443]: {"time":1725453866467,"response":"startAirplayPlayback Success"} Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:13 kmusic systemd[1]: shairport-sync.service: Succeeded. Sep 04 20:47:13 kmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::ClearQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::stPlaybackTimer Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::updateTrackBlock Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrackBlock Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::serviceStop Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::stop Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::clearPlayQueue Sep 04 20:47:13 kmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::saveQueue Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic sudo[8781]: pam_unix(sudo:session): session closed for user root Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushQueue Sep 04 20:47:13 kmusic volumio[719]: info: Shairport-Sync retarted Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand stop took 91 milliseconds Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:13 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand status took 18 milliseconds Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand status took 13 milliseconds Sep 04 20:47:13 kmusic volumio[719]: info: sendMpdCommand status took 8 milliseconds Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:13 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: 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} Sep 04 20:47:13 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState stateService stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: No code Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: 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} Sep 04 20:47:13 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState stateService stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: No code Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: 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} Sep 04 20:47:13 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState stateService stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: No code Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 108ms Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 102ms Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 99ms Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:13 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:13 kmusic volumio[719]: info: Sep 04 20:47:13 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:13 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:13 kmusic volumio[719]: error: updateQueue error: null Sep 04 20:47:13 kmusic volumio[719]: error: updateQueue error: null Sep 04 20:47:13 kmusic volumio[719]: error: updateQueue error: null Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 106ms Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 11ms Sep 04 20:47:13 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:13 kmusic volumio[719]: info: Starting UPNP Playback Sep 04 20:47:13 kmusic volumio[719]: info: Preparing playback through UPNP Sep 04 20:47:13 kmusic volumio[719]: info: CoreCommandRouter::volumioGetState Sep 04 20:47:13 kmusic volumio[719]: info: CorePlayQueue::getTrack 0 Sep 04 20:47:13 kmusic volumio[719]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 8ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 6ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 4ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 5ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 3ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 23 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 22 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 31 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 17 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 10 milliseconds Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Adele - Someone Like You (黑胶版).flac","artist":"未知歌手","album":null,"uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus stop Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Adele - Someone Like You (黑胶版).flac","artist":"未知歌手","album":null,"uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 80ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 87ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 51 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 50 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 47 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 11 milliseconds Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 179ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 165ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 154ms Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces system playlist update Sep 04 20:47:14 kmusic volumio[719]: info: Ignoring MPD Status Update Sep 04 20:47:14 kmusic volumio[719]: info: Sep 04 20:47:14 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 158ms Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 155 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 146 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 58ms Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 55 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 53ms Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand status took 50 milliseconds Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"507 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 204ms Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 47 milliseconds Sep 04 20:47:14 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 45 milliseconds Sep 04 20:47:14 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"493 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:14 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:14 kmusic volumio[719]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"493 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:14 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState stateService play Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:14 kmusic volumio[719]: info: Received an update from plugin. extracting info from payload Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 295ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 195ms Sep 04 20:47:14 kmusic volumio[719]: info: ------------------------------ 192ms Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:14 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:14 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:15 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:19 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:20 kmusic volumio[8711]: 56 Sep 04 20:47:20 kmusic volumio[8711]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 20:47:20 kmusic volumio[8711]: Dload Upload Total Spent Left Speed Sep 04 20:47:20 kmusic volumio[719]: info: Sep 04 20:47:20 kmusic volumio[719]: ---------------------------- Client requests Volume 56 Sep 04 20:47:20 kmusic volumio[719]: info: VolumeController::SetAlsaVolume56 Sep 04 20:47:20 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:20 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:20 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:20 kmusic volumio[8711]: [158B blob data] Sep 04 20:47:20 kmusic volumio[8711]: {"time":1725454040761,"response":"volume Success"}56 Sep 04 20:47:20 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:20 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:20 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:22 kmusic volumio[8711]: 100 Sep 04 20:47:22 kmusic volumio[8711]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 20:47:22 kmusic volumio[8711]: Dload Upload Total Spent Left Speed Sep 04 20:47:22 kmusic volumio[719]: info: Sep 04 20:47:22 kmusic volumio[719]: ---------------------------- Client requests Volume 100 Sep 04 20:47:22 kmusic volumio[719]: info: VolumeController::SetAlsaVolume100 Sep 04 20:47:22 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:22 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:22 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:22 kmusic volumio[8711]: [158B blob data] Sep 04 20:47:22 kmusic volumio[8711]: {"time":1725454042228,"response":"volume Success"}100 Sep 04 20:47:22 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:22 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:22 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:23 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:27 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:30 kmusic volumio[719]: info: Sep 04 20:47:30 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:30 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:30 kmusic volumio[719]: info: Sep 04 20:47:30 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:30 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:30 kmusic volumio[719]: info: Sep 04 20:47:30 kmusic volumio[719]: ---------------------------- MPD announces state update: player Sep 04 20:47:30 kmusic volumio[719]: info: ControllerMpd::getState Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand status Sep 04 20:47:30 kmusic volumio[719]: info: sendMpdCommand status took 8 milliseconds Sep 04 20:47:30 kmusic volumio[719]: info: sendMpdCommand status took 5 milliseconds Sep 04 20:47:30 kmusic volumio[719]: info: sendMpdCommand status took 3 milliseconds Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::parseState Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 20:47:30 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 04 20:47:30 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 04 20:47:30 kmusic volumio[719]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:30 kmusic volumio[719]: verbose: ControllerMpd::parseTrackInfo Sep 04 20:47:30 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:30 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:30 kmusic volumio[719]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17342,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"681 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:30 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::syncState stateService pause Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::stPlaybackTimer Sep 04 20:47:30 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:30 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:30 kmusic volumio[719]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17342,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"681 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:30 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::syncState stateService pause Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::stPlaybackTimer Sep 04 20:47:30 kmusic volumio[719]: info: ControllerMpd::pushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::servicePushState Sep 04 20:47:30 kmusic volumio[719]: verbose: In UPNP mode Sep 04 20:47:30 kmusic volumio[719]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17342,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"681 Kbps","isStreaming":false,"title":"Someone Like You (黑胶版)","artist":"Adele","album":"21（黑胶版）","uri":"http://10.0.0.9:5005/music/Adele%20-%20Someone%20Like%20You%20(%E9%BB%91%E8%83%B6%E7%89%88).flac","trackType":"flac"} Sep 04 20:47:30 kmusic volumio[719]: verbose: CURRENT POSITION 0 Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::syncState stateService pause Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::syncState currentStatus play Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::pushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::volumioPushState Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::stPlaybackTimer Sep 04 20:47:30 kmusic volumio[719]: info: ------------------------------ 43ms Sep 04 20:47:30 kmusic volumio[719]: info: ------------------------------ 41ms Sep 04 20:47:30 kmusic volumio[719]: info: ------------------------------ 39ms Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:30 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:30 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:30 kmusic volumio[719]: info: CoreCommandRouter::volumioGetQueue Sep 04 20:47:30 kmusic volumio[719]: info: CoreStateMachine::getQueue Sep 04 20:47:30 kmusic volumio[719]: info: CorePlayQueue::getQueue Sep 04 20:47:31 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:35 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:38 kmusic volumio[719]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 04 20:47:38 kmusic volumio[719]: info: Retrying Login Due to Network Error Sep 04 20:47:39 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:43 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:47 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:48 kmusic volumio[719]: info: MyVolumio login type: Token Sep 04 20:47:51 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:55 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:47:59 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:03 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:07 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:11 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:15 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:18 kmusic volumio[719]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 04 20:48:18 kmusic volumio[719]: info: Retrying Login Due to Network Error Sep 04 20:48:19 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:23 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:27 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:28 kmusic volumio[719]: info: MyVolumio login type: Token Sep 04 20:48:31 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:35 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:39 kmusic volumio[719]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 04 20:48:39 kmusic volumio[719]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 20:48:40 kmusic volumio[719]: Error: connect ETIMEDOUT 192.133.77.59:443 Sep 04 20:48:40 kmusic volumio[719]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 04 20:48:40 kmusic volumio[719]: errno: -110, Sep 04 20:48:40 kmusic volumio[719]: code: 'ETIMEDOUT', Sep 04 20:48:40 kmusic volumio[719]: syscall: 'connect', Sep 04 20:48:40 kmusic volumio[719]: address: '192.133.77.59', Sep 04 20:48:40 kmusic volumio[719]: port: 443 Sep 04 20:48:40 kmusic volumio[719]: } Sep 04 20:48:40 kmusic volumio[719]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 20:48:41 kmusic sudo[8953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 20:47 Sep 04 20:48:41 kmusic sudo[8953]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"