-- Logs begin at Fri 2025-10-17 21:20:11 CST, end at Fri 2025-10-17 21:35:06 CST. -- Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 596 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 596 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand stop took 25 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲04/聪明糊涂心.mp3 Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 7 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand stop took 5 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"千年等一回","artist":"【bbs.iido.org.cn】","album":"高胜美-老歌金曲(黑胶CD)","uri":"NAS/FNOS/经典歌曲07/千年等一回.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: No code Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 45ms Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:16 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand clear took 36 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲04/聪明糊涂心.mp3" Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:16 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 46ms Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 45ms Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:16 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:16 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:16 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:16 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 28ms Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲04/聪明糊涂心.mp3" took 24 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 19ms Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 17ms Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:16 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:16 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 8ms Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand play took 7 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 6ms Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 5ms Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: Oct 17 21:34:16 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 8 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":311,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":311,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:16 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":311,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:16 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 30ms Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 62ms Oct 17 21:34:16 volumio volumio[1050]: info: ------------------------------ 62ms Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 17 21:34:16 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 17 21:34:16 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":311,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:16 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":311,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:16 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":311,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:16 volumio volumio[1050]: verbose: CURRENT POSITION 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:16 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:16 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:16 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:16 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:17 volumio volumio[1050]: info: ------------------------------ 121ms Oct 17 21:34:17 volumio volumio[1050]: info: ------------------------------ 115ms Oct 17 21:34:17 volumio volumio[1050]: info: ------------------------------ 114ms Oct 17 21:34:17 volumio volumio[1050]: info: VolumeController::SetAlsaVolume49 Oct 17 21:34:17 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:17 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:17 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:17 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 331 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand stop took 28 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3 Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand stop took 4 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 1 milliseconds Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:19 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand clear took 3 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3" Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"聪明糊涂心","artist":"聪明糊涂心","album":"东方之珠06：世间情歌","uri":"NAS/FNOS/经典歌曲04/聪明糊涂心.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: No code Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: No code Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 67ms Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 62ms Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 59ms Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:19 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:19 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:19 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:19 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 62ms Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3" took 60 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:19 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:19 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 20ms Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand play took 18 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 18ms Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 17ms Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: Oct 17 21:34:19 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 9 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:19 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:19 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 31ms Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 52ms Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 51ms Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 43 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand status took 43 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:19 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 81ms Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 17 21:34:19 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:19 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:19 volumio volumio[1050]: verbose: CURRENT POSITION 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:19 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:19 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:19 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 123ms Oct 17 21:34:19 volumio volumio[1050]: info: ------------------------------ 120ms Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 333 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand stop took 31 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲07/听海.mp3 Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand stop took 4 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:20 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand clear took 3 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲07/听海.mp3" Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"打了一把钥匙给你","artist":"打了一把钥匙给你","album":"东方之珠07：一往情深","uri":"NAS/FNOS/经典歌曲04/打了一把钥匙给你.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: No code Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:20 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 39ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 35ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 34ms Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:20 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:20 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:20 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:20 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 32ms Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲07/听海.mp3" took 30 milliseconds Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 8ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 7ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 8ms Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand play took 7 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:20 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:20 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 6 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: Oct 17 21:34:20 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 34 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 32 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand status took 31 milliseconds Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:20 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:20 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 60ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 81ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 80ms Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 17 21:34:20 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 17 21:34:20 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:20 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:20 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:20 volumio volumio[1050]: verbose: CURRENT POSITION 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:20 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:20 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:20 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 133ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 131ms Oct 17 21:34:20 volumio volumio[1050]: info: ------------------------------ 130ms Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 548 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand stop took 34 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲04/挪威森林.mp3 Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand stop took 4 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 1 milliseconds Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:21 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand clear took 2 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲04/挪威森林.mp3" Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"听海","artist":"张惠妹","album":"情歌最毒","uri":"NAS/FNOS/经典歌曲07/听海.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: No code Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:21 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 38ms Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 36ms Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 35ms Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:21 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:21 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:21 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:21 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 33ms Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲04/挪威森林.mp3" took 31 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:21 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:21 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 8ms Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand play took 6 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 6ms Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 7ms Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 30 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: Oct 17 21:34:21 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 8 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":395,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":395,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:21 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":395,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:21 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 54ms Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 44ms Oct 17 21:34:21 volumio volumio[1050]: info: ------------------------------ 44ms Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:21 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 17 21:34:21 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":395,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:21 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":395,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:21 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":395,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:21 volumio volumio[1050]: verbose: CURRENT POSITION 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:21 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:21 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:21 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:21 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:22 volumio volumio[1050]: info: ------------------------------ 111ms Oct 17 21:34:22 volumio volumio[1050]: info: ------------------------------ 107ms Oct 17 21:34:22 volumio volumio[1050]: info: ------------------------------ 107ms Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 395 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand stop took 28 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲01/还要爱你.mp3 Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand stop took 2 milliseconds Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:23 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand clear took 10 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand status took 9 milliseconds Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲01/还要爱你.mp3" Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"挪威森林","artist":"群星","album":"重拾男人心 CD2","uri":"NAS/FNOS/经典歌曲04/挪威森林.mp3","trackType":"mp3"} Oct 17 21:34:23 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: No code Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 21:34:23 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: No code Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 110ms Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 105ms Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:23 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:23 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:23 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:23 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand status took 100 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 99ms Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲01/还要爱你.mp3" took 98 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 4ms Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:23 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:23 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 21:34:23 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: No code Oct 17 21:34:23 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:23 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:23 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 132ms Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:23 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:23 volumio volumio[1050]: info: Oct 17 21:34:23 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:23 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 39ms Oct 17 21:34:23 volumio volumio[1050]: info: sendMpdCommand play took 39 milliseconds Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 15ms Oct 17 21:34:23 volumio volumio[1050]: info: ------------------------------ 14ms Oct 17 21:34:24 volumio volumio[1050]: info: Oct 17 21:34:24 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:24 volumio volumio[1050]: info: Oct 17 21:34:24 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:24 volumio volumio[1050]: info: Oct 17 21:34:24 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:24 volumio volumio[1050]: info: Oct 17 21:34:24 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:24 volumio volumio[1050]: info: Oct 17 21:34:24 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:24 volumio volumio[1050]: info: Oct 17 21:34:24 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:24 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:24 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:24 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:24 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:24 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: ------------------------------ 22ms Oct 17 21:34:24 volumio volumio[1050]: info: ------------------------------ 40ms Oct 17 21:34:24 volumio volumio[1050]: info: ------------------------------ 40ms Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:24 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 17 21:34:24 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:24 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:24 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:24 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:24 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:24 volumio volumio[1050]: verbose: CURRENT POSITION 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:24 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:24 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:24 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:24 volumio volumio[1050]: info: ------------------------------ 91ms Oct 17 21:34:24 volumio volumio[1050]: info: ------------------------------ 89ms Oct 17 21:34:24 volumio volumio[1050]: info: ------------------------------ 88ms Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 127 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand stop took 24 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲01/好男人.mp3 Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 8 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand stop took 8 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 6 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:25 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand clear took 2 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲01/好男人.mp3" Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"还要爱你","artist":"还要爱你","album":"东方之珠：爱的初体验","uri":"NAS/FNOS/经典歌曲01/还要爱你.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: No code Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:25 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 45ms Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 36ms Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 35ms Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:25 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:25 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:25 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:25 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 31ms Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲01/好男人.mp3" took 29 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 4ms Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:25 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:25 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 7ms Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand play took 6 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 5ms Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: Oct 17 21:34:25 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:25 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:25 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 23ms Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 42ms Oct 17 21:34:25 volumio volumio[1050]: info: ------------------------------ 41ms Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 17 21:34:25 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:25 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:25 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:25 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":319,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:25 volumio volumio[1050]: verbose: CURRENT POSITION 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:25 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:25 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:25 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:25 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 100ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 97ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 98ms Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioNext Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::next Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 144 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand stop took 29 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲08/无言的结局.mp3 Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand stop took 4 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:26 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand clear took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲08/无言的结局.mp3" Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"好男人","artist":"张镐哲","album":"好男人","uri":"NAS/FNOS/经典歌曲01/好男人.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: No code Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:26 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 47ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 45ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 44ms Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:26 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:26 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:26 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:26 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 42ms Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲08/无言的结局.mp3" took 41 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 4ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 3ms Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:26 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:26 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 9ms Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand play took 8 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 7ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 5ms Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: Oct 17 21:34:26 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:26 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:26 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 22ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 40ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 40ms Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 17 21:34:26 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 17 21:34:26 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:26 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:26 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:26 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:26 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:26 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:26 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 125ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 122ms Oct 17 21:34:26 volumio volumio[1050]: info: ------------------------------ 121ms Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioSeek Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::seek Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: TRACKBLOCK {"uri":"mnt/NAS/FNOS/经典歌曲08/无言的结局.mp3","service":"mpd","name":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=828&web=%E6%9D%8E%E8%8C%82%E5%B1%B1%EF%BC%8C%E6%9E%97%E6%B7%91%E5%AE%B9/%E6%88%90%E5%90%8D%E9%87%91%E6%9B%B2/extralarge&path=%2FNAS%2FFNOS%2F%E7%BB%8F%E5%85%B8%E6%AD%8C%E6%9B%B208&metadata=false","duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::seek Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: error: null Oct 17 21:34:29 volumio volumio[1050]: info: Oct 17 21:34:29 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:29 volumio volumio[1050]: info: Oct 17 21:34:29 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:29 volumio volumio[1050]: info: Oct 17 21:34:29 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:29 volumio volumio[1050]: info: sendMpdCommand status took 6 milliseconds Oct 17 21:34:29 volumio volumio[1050]: info: sendMpdCommand status took 7 milliseconds Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:29 volumio volumio[1050]: info: sendMpdCommand status took 8 milliseconds Oct 17 21:34:29 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:29 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12000,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:29 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:29 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12000,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:29 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:29 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: info: ------------------------------ 59ms Oct 17 21:34:29 volumio volumio[1050]: info: ------------------------------ 55ms Oct 17 21:34:29 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 17 21:34:29 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:29 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12728,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:29 volumio volumio[1050]: verbose: CURRENT POSITION 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:29 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:29 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:29 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:29 volumio volumio[1050]: info: ------------------------------ 78ms Oct 17 21:34:31 volumio volumio[1050]: info: VolumeController::SetAlsaVolume50 Oct 17 21:34:31 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:31 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:31 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:31 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPlay Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::play index 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 645 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand stop took 54 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::play index undefined Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::startPlaybackTimer Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/经典歌曲08/葬心.mp3 Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand stop took 4 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 2 milliseconds Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:37 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand clear took 2 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/经典歌曲08/葬心.mp3" Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"无言的结局","artist":"李茂山，林淑容","album":"成名金曲","uri":"NAS/FNOS/经典歌曲08/无言的结局.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: No code Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:37 volumio volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 38ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 35ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 34ms Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:37 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:37 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:37 volumio volumio[1050]: error: updateQueue error: null Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:37 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 32ms Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand add "NAS/FNOS/经典歌曲08/葬心.mp3" took 31 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 4ms Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand play Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:37 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 15ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 13ms Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand play took 12 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 11ms Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces system playlist update Oct 17 21:34:37 volumio volumio[1050]: info: Ignoring MPD Status Update Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 15ms Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 5 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: Oct 17 21:34:37 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 6 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand status took 4 milliseconds Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:37 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:37 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 25ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 42ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 42ms Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:37 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:37 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:37 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:37 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState stateService play Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus play Oct 17 21:34:37 volumio volumio[1050]: info: Received an update from plugin. extracting info from payload Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:37 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:37 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 109ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 110ms Oct 17 21:34:37 volumio volumio[1050]: info: ------------------------------ 109ms Oct 17 21:34:39 volumio sudo[4638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 21:34:39 volumio sudo[4638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:34:39 volumio sudo[4638]: pam_unix(sudo:session): session closed for user root Oct 17 21:34:39 volumio sudo[4640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 21:34:39 volumio sudo[4640]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:34:39 volumio sudo[4640]: pam_unix(sudo:session): session closed for user root Oct 17 21:34:39 volumio volumio[1050]: verbose: New Socket.io Connection to 192.168.0.74 from 192.168.0.65 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Edg/141.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 17 21:34:39 volumio sudo[4644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 21:34:39 volumio sudo[4644]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:34:39 volumio sudo[4644]: pam_unix(sudo:session): session closed for user root Oct 17 21:34:39 volumio sudo[4646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 21:34:39 volumio sudo[4646]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 21:34:39 volumio sudo[4646]: pam_unix(sudo:session): session closed for user root Oct 17 21:34:39 volumio volumio[1050]: verbose: New Socket.io Connection to 192.168.0.74 from 192.168.0.65 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Edg/141.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 17 21:34:39 volumio volumio[1050]: info: CoreCommandRouter::volumioGetState Oct 17 21:34:39 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:39 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 21:34:39 volumio volumio[1050]: info: Listing playlists Oct 17 21:34:39 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 21:34:39 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 17 21:34:39 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 17 21:34:39 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 21:34:41 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 21:34:41 volumio volumio[1050]: info: Received Get System Info Oct 17 21:34:41 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 21:34:41 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 21:34:41 volumio volumio[1050]: info: Discovery: Getting this device information Oct 17 21:34:41 volumio volumio[1050]: info: CoreCommandRouter::volumioGetState Oct 17 21:34:41 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:41 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 21:34:44 volumio volumio[1050]: info: Airplay started streaming, receiving metadatas Oct 17 21:34:44 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:44 volumio volumio[1050]: info: CoreCommandRouter::volumioStop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::stPlaybackTimer Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:44 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:44 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:44 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:44 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::serviceStop Oct 17 21:34:44 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:44 volumio volumio[1050]: info: CoreCommandRouter::serviceStop Oct 17 21:34:44 volumio volumio[1050]: info: ControllerMpd::stop Oct 17 21:34:44 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:44 volumio volumio[1050]: info: Airplay started streaming, receiving metadatas Oct 17 21:34:44 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:44 volumio volumio[1050]: info: CoreCommandRouter::volumioStop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:44 volumio volumio[1050]: info: Airplay started streaming, receiving metadatas Oct 17 21:34:44 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:44 volumio volumio[1050]: info: CoreCommandRouter::volumioStop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:44 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:45 volumio volumio[1050]: info: Oct 17 21:34:45 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand stop took 36 milliseconds Oct 17 21:34:45 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:45 volumio volumio[1050]: info: Oct 17 21:34:45 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:45 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:45 volumio volumio[1050]: info: Oct 17 21:34:45 volumio volumio[1050]: ---------------------------- MPD announces state update: player Oct 17 21:34:45 volumio volumio[1050]: info: ControllerMpd::getState Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand status Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand status took 3 milliseconds Oct 17 21:34:45 volumio shairport-sync[3993]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 21:34:45 volumio shairport-sync[3993]: Dload Upload Total Spent Left Speed Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand status took 6 milliseconds Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::parseState Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 21:34:45 volumio volumio[1050]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:45 volumio volumio[1050]: verbose: ControllerMpd::parseTrackInfo Oct 17 21:34:45 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:45 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: No code Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:45 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: No code Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: ControllerMpd::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"葬心","artist":"黃鶯鶯","album":"東方之珠 III [dBS+1]","uri":"NAS/FNOS/经典歌曲08/葬心.mp3","trackType":"mp3"} Oct 17 21:34:45 volumio volumio[1050]: verbose: CURRENT POSITION 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::syncState stateService stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::syncState currentStatus stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: No code Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: ------------------------------ 81ms Oct 17 21:34:45 volumio volumio[1050]: info: ------------------------------ 78ms Oct 17 21:34:45 volumio volumio[1050]: info: Updating volume from AirPlay: -11.96; 60% Oct 17 21:34:45 volumio volumio[1050]: info: VolumeController::SetAlsaVolume60 Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: Oct 17 21:34:45 volumio volumio[1050]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 17 21:34:45 volumio volumio[1050]: info: Airplay playback start Oct 17 21:34:45 volumio volumio[1050]: info: CorePlayQueue::getTrack 650 Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioStop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::stop Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 21:34:45 volumio shairport-sync[3993]: [237B blob data] Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:45 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:46 volumio volumio[1050]: info: Updating volume from AirPlay: -12.88; 57% Oct 17 21:34:46 volumio volumio[1050]: info: VolumeController::SetAlsaVolume57 Oct 17 21:34:46 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:46 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioGetQueue Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::getQueue Oct 17 21:34:50 volumio volumio[1050]: info: CorePlayQueue::getQueue Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::servicePushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:50 volumio shairport-sync[3993]: {"time":1760708085153,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 21:34:50 volumio shairport-sync[3993]: Dload Upload Total Spent Left Speed Oct 17 21:34:50 volumio volumio[1050]: info: Oct 17 21:34:50 volumio volumio[1050]: ---------------------------- Client requests Stop Airplay Playback Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 17 21:34:50 volumio volumio[1050]: info: Airplay playback stop Oct 17 21:34:50 volumio volumio[1050]: info: Airplay Playback Stopped, clearing state Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::resetVolumioState Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::getcurrentVolume Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 21:34:50 volumio shairport-sync[3993]: [158B blob data] Oct 17 21:34:50 volumio volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E6%98%8E%E7%9C%9F/%E5%8F%98%E5%BF%83%E7%9A%84%E7%BF%85%E8%86%80/3a9c8b65-94c6-4128-8f53-3daae29da526.jpg' Oct 17 21:34:50 volumio volumio[1050]: info: VolumeController:: Volume=56 Mute =false Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:50 volumio volumio[1050]: info: CoreStateMachine::updateTrackBlock Oct 17 21:34:50 volumio volumio[1050]: info: CorePlayQueue::getTrackBlock Oct 17 21:34:50 volumio volumio[1050]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 21:34:51 volumio volumio[1050]: info: VolumeController:: Volume=56 Mute =false Oct 17 21:34:51 volumio volumio[1050]: info: CoreStateMachine::pushState Oct 17 21:34:51 volumio volumio[1050]: info: CoreCommandRouter::volumioPushState Oct 17 21:34:58 volumio volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%B3%E6%85%A7%E5%AB%BB/%E6%B0%B8%E9%81%A0%E6%98%AF%E4%BD%A0%E7%9A%84%E9%99%B3%E6%85%A7%E5%AB%BB/d365f4cb-8265-4666-b608-ce8a702ace6d.jpg' Oct 17 21:34:58 volumio volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/%E7%9C%9F%E7%9A%84%E7%88%B1%E4%BD%A0/c422c7ad-d563-44a8-97b0-86b1ab83e3c3.jpg' Oct 17 21:35:00 volumio volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%94%A1%E7%90%B4/%E8%AF%95%E9%9F%B3%20%E8%94%A1%E7%90%B4/8e9629d6-db69-4164-a5eb-1fc14d55f5bb.png' Oct 17 21:35:04 volumio volumio[1050]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E6%98%8E%E7%9C%9F/%E5%8F%98%E5%BF%83%E7%9A%84%E7%BF%85%E8%86%80/3a9c8b65-94c6-4128-8f53-3daae29da526.jpg' Oct 17 21:35:06 volumio volumio[1050]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 21:35:06 volumio volumio[1050]: Error: connect ETIMEDOUT 31.13.96.193:443 Oct 17 21:35:06 volumio volumio[1050]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 17 21:35:06 volumio volumio[1050]: errno: -110, Oct 17 21:35:06 volumio volumio[1050]: code: 'ETIMEDOUT', Oct 17 21:35:06 volumio volumio[1050]: syscall: 'connect', Oct 17 21:35:06 volumio volumio[1050]: address: '31.13.96.193', Oct 17 21:35:06 volumio volumio[1050]: port: 443 Oct 17 21:35:06 volumio volumio[1050]: } Oct 17 21:35:06 volumio volumio[1050]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 21:35:06 volumio sudo[4740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-17 21:34 Oct 17 21:35:06 volumio sudo[4740]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"