-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Tue 2025-05-20 16:23:16 CST. -- May 20 16:22:00 volumio volumio[957]: info: Preload queue cleared May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::ClearQueue May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::stop May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::stPlaybackTimer May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrackBlock May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 16 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::serviceStop May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 16 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::serviceStop May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::stop May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::saveQueue May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::addQueueItems May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::addQueueItems May 20 16:22:00 volumio volumio[957]: info: Preload queue cleared May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/005a_540e_560c_707d1e84b4cdc286221bafd9037218a1.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/005a_540e_560c_707d1e84b4cdc286221bafd9037218a1.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/f00c_ab5b_c1b2_e7cc6990bd2495516380fe3ffab58a97.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/f00c_ab5b_c1b2_e7cc6990bd2495516380fe3ffab58a97.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28284363134_ace9_c860_2667_fa53b9963c0d3a8634b4c5ef812a0b12.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28284363134_ace9_c860_2667_fa53b9963c0d3a8634b4c5ef812a0b12.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28481668362_e6b7_bc62_ac79_b92c48df5b4f056b880ee71d8db939bf.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28481668362_e6b7_bc62_ac79_b92c48df5b4f056b880ee71d8db939bf.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28481671114_9bae_f823_729a_ba5dc0a311f7cf7644fa6074697a7660.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28481671114_9bae_f823_729a_ba5dc0a311f7cf7644fa6074697a7660.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28482126608_f7c8_0d0d_6bb8_288fa7c7c1f2ee1423d938e7325583c2.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_28482126608_f7c8_0d0d_6bb8_288fa7c7c1f2ee1423d938e7325583c2.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_29923181802_07d9_52ae_841f_b65e2c1fbcfe645657d064bd8a1fe3fa.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_29923181802_07d9_52ae_841f_b65e2c1fbcfe645657d064bd8a1fe3fa.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_32003202064_6298_aed4_fe85_e5e7219cb323c160a1dad604ae92e2f9.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_32003202064_6298_aed4_fe85_e5e7219cb323c160a1dad604ae92e2f9.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_34400821874_e5c1_b1b6_9457_122d5715318fac048057819d67bdfc2b.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_34400821874_e5c1_b1b6_9457_122d5715318fac048057819d67bdfc2b.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_34813093827_b37e_3099_69d3_2cb4871ddfde38f4f5bd750439b90b97.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_34813093827_b37e_3099_69d3_2cb4871ddfde38f4f5bd750439b90b97.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_35042021530_284a_c769_411c_ab91c7080cb1a477c210d01d2658f1ab.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_35042021530_284a_c769_411c_ab91c7080cb1a477c210d01d2658f1ab.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_44757316907_3c02_8f8f_6127_d9cfe3c126c86277a337079621a5e7b5.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_44757316907_3c02_8f8f_6127_d9cfe3c126c86277a337079621a5e7b5.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_44893731914_a58d_d9f7_0597_48aa884f2ec8e4329527c5f4455099b7.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_44893731914_a58d_d9f7_0597_48aa884f2ec8e4329527c5f4455099b7.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_55802438942_2e4c_ae5a_94da_c79def4464a32659e9bf7bd204b81bfb.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/obj_wo3DlMOGwrbDjj7DisKw_55802438942_2e4c_ae5a_94da_c79def4464a32659e9bf7bd204b81bfb.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/彩云伴海鸥 (Single Version) - 梁红 (1).mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/彩云伴海鸥 (Single Version) - 梁红 (1).mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/彩云伴海鸥 (Single Version) - 梁红.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/彩云伴海鸥 (Single Version) - 梁红.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/我的快乐就是想你 - 黄平.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/我的快乐就是想你 - 黄平.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/故乡情.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/故乡情.mp3 May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/红尘情歌 - 高安.mp3 May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/红尘情歌 - 高安.mp3 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::saveQueue May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrackBlock May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPlay May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::play index 18 May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::addQueueItems May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::addQueueItems May 20 16:22:00 volumio volumio[957]: info: Preload queue cleared May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400001349aB4XqBk4.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400001349aB4XqBk4.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/听闻远方有你.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/听闻远方有你.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/想你的时候问月亮 (温柔女声版) - 陈依梦.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/想你的时候问月亮 (温柔女声版) - 陈依梦.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400001Gr1ye12YxLG.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400001Gr1ye12YxLG.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400000ky4wc42VVij.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400000ky4wc42VVij.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400000nItUW3vAeJC.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400000nItUW3vAeJC.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400003808aG2VikMA.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400003808aG2VikMA.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/三生石下. - 大欢.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/三生石下. - 大欢.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400004ONgch3sg5Dn.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400004ONgch3sg5Dn.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400002hmg361fG2Sj.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400002hmg361fG2Sj.m4a May 20 16:22:00 volumio volumio[957]: info: Adding Item to queue: music-library/USB/16_GB/C400001ZapJO0wFJLo.m4a May 20 16:22:00 volumio volumio[957]: info: Using cached record of: music-library/USB/16_GB/C400001ZapJO0wFJLo.m4a May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::stop May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::saveQueue May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::play index undefined May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrackBlock May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::clearAddPlayTracks USB/16_GB/红尘情歌 - 高安.mp3 May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand stop took 205 milliseconds May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand stop took 34 milliseconds May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:00 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand status took 56 milliseconds May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand clear took 55 milliseconds May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand status took 45 milliseconds May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand status took 41 milliseconds May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand add "USB/16_GB/红尘情歌 - 高安.mp3" May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: 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} May 20 16:22:00 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: No code May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: 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} May 20 16:22:00 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: No code May 20 16:22:00 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:00 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:00 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 224ms May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 217ms May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:00 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:00 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:00 volumio volumio[957]: error: updateQueue error: null May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:00 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 198ms May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand playlistinfo took 190 milliseconds May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand add "USB/16_GB/红尘情歌 - 高安.mp3" took 191 milliseconds May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 19ms May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 16ms May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play May 20 16:22:00 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 266ms May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:00 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:00 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 55ms May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand play took 46 milliseconds May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 45ms May 20 16:22:00 volumio volumio[957]: info: ------------------------------ 42ms May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand status took 24 milliseconds May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand status took 22 milliseconds May 20 16:22:00 volumio volumio[957]: info: sendMpdCommand status took 16 milliseconds May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:00 volumio volumio[957]: info: May 20 16:22:00 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:00 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:00 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand status took 44 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand playlistinfo took 33 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand playlistinfo took 31 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand playlistinfo took 29 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand status took 23 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand status took 18 milliseconds May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:01 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:01 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop May 20 16:22:01 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:01 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:01 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:01 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:01 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: ------------------------------ 156ms May 20 16:22:01 volumio volumio[957]: info: ------------------------------ 220ms May 20 16:22:01 volumio volumio[957]: info: ------------------------------ 210ms May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand playlistinfo took 151 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand playlistinfo took 148 milliseconds May 20 16:22:01 volumio volumio[957]: info: sendMpdCommand playlistinfo took 146 milliseconds May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:01 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:01 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":619,"duration":205,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:01 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:01 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":205,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:01 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:01 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":205,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:01 volumio volumio[957]: verbose: CURRENT POSITION 18 May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:01 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:01 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:01 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:01 volumio volumio[957]: info: ------------------------------ 454ms May 20 16:22:01 volumio volumio[957]: info: ------------------------------ 430ms May 20 16:22:01 volumio volumio[957]: info: ------------------------------ 427ms May 20 16:22:08 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 20 16:22:08 volumio volumio[957]: info: CURURI: music-library May 20 16:22:08 volumio volumio[957]: info: Preload queue cleared May 20 16:22:12 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 20 16:22:12 volumio volumio[957]: info: CURURI: albums:// May 20 16:22:12 volumio volumio[957]: info: listAlbums - loading Albums from cache May 20 16:22:12 volumio volumio[957]: info: Preload queue cleared May 20 16:22:17 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 20 16:22:17 volumio volumio[957]: info: CURURI: albums:// May 20 16:22:17 volumio volumio[957]: info: listAlbums - loading Albums from cache May 20 16:22:17 volumio volumio[957]: info: Preload queue cleared May 20 16:22:19 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 20 16:22:19 volumio volumio[957]: info: CURURI: albums://%E7%8E%8B%E5%AE%87%E5%AE%99Leto/%E8%8B%A5%E6%9C%88%E4%BA%AE%E6%B2%A1%E6%9D%A5%20(%E5%90%89%E4%BB%96%E7%89%88) May 20 16:22:19 volumio volumio[957]: info: Preload queue cleared May 20 16:22:19 volumio volumio[957]: info: No valid Plugin REST Endpoint May 20 16:22:20 volumio volumio[957]: error: error May 20 16:22:20 volumio volumio[957]: error: error May 20 16:22:22 volumio volumio[957]: info: Preload queue cleared May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::ClearQueue May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::stop May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::stPlaybackTimer May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrackBlock May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::serviceStop May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 18 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::serviceStop May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::stop May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::saveQueue May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::addQueueItems May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::addQueueItems May 20 16:22:22 volumio volumio[957]: info: Preload queue cleared May 20 16:22:22 volumio volumio[957]: info: Adding Item to queue: albums://%E7%8E%8B%E5%AE%87%E5%AE%99Leto/%E8%8B%A5%E6%9C%88%E4%BA%AE%E6%B2%A1%E6%9D%A5%20(%E5%90%89%E4%BB%96%E7%89%88) May 20 16:22:22 volumio volumio[957]: info: Exploding uri albums://%E7%8E%8B%E5%AE%87%E5%AE%99Leto/%E8%8B%A5%E6%9C%88%E4%BA%AE%E6%B2%A1%E6%9D%A5%20(%E5%90%89%E4%BB%96%E7%89%88) in service mpd May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand stop took 74 milliseconds May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::saveQueue May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrackBlock May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPlay May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::play index 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::stop May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::play index undefined May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::clearAddPlayTracks USB/16_GB/C400004ONgch3sg5Dn.m4a May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 57 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand stop took 21 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 19 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 16 milliseconds May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:22 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 14 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand clear took 13 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 13 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 13 milliseconds May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand add "USB/16_GB/C400004ONgch3sg5Dn.m4a" May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: 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":"红尘情歌 - 高安.mp3","artist":null,"album":null,"uri":"USB/16_GB/红尘情歌 - 高安.mp3","trackType":"mp3"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: No code May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 20 16:22:22 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 207ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 163ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 160ms May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:22 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:22 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:22 volumio volumio[957]: error: updateQueue error: null May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:22 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 147ms May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand add "USB/16_GB/C400004ONgch3sg5Dn.m4a" took 139 milliseconds May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 20ms May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:22 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 57ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 49ms May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand play took 40 milliseconds May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 37ms May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces system playlist update May 20 16:22:22 volumio volumio[957]: info: Ignoring MPD Status Update May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 17ms May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 19 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 17 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 22 milliseconds May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: May 20 16:22:22 volumio volumio[957]: ---------------------------- MPD announces state update: player May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::getState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 43 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 24 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 24 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 23 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 16 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand status took 12 milliseconds May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseState May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"若月亮没来","artist":"王宇宙Leto","album":"若月亮没来 (吉他版)","uri":"USB/16_GB/C400004ONgch3sg5Dn.m4a","trackType":"m4a"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"若月亮没来","artist":"王宇宙Leto","album":"若月亮没来 (吉他版)","uri":"USB/16_GB/C400004ONgch3sg5Dn.m4a","trackType":"m4a"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:22 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"若月亮没来","artist":"王宇宙Leto","album":"若月亮没来 (吉他版)","uri":"USB/16_GB/C400004ONgch3sg5Dn.m4a","trackType":"m4a"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:22 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 152ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 227ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 224ms May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 164 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 159 milliseconds May 20 16:22:22 volumio volumio[957]: info: sendMpdCommand playlistinfo took 159 milliseconds May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"若月亮没来","artist":"王宇宙Leto","album":"若月亮没来 (吉他版)","uri":"USB/16_GB/C400004ONgch3sg5Dn.m4a","trackType":"m4a"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:22 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"若月亮没来","artist":"王宇宙Leto","album":"若月亮没来 (吉他版)","uri":"USB/16_GB/C400004ONgch3sg5Dn.m4a","trackType":"m4a"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:22 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: ControllerMpd::pushState May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::servicePushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"若月亮没来","artist":"王宇宙Leto","album":"若月亮没来 (吉他版)","uri":"USB/16_GB/C400004ONgch3sg5Dn.m4a","trackType":"m4a"} May 20 16:22:22 volumio volumio[957]: verbose: CURRENT POSITION 0 May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState stateService play May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play May 20 16:22:22 volumio volumio[957]: info: Received an update from plugin. extracting info from payload May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: CoreStateMachine::pushState May 20 16:22:22 volumio volumio[957]: info: CorePlayQueue::getTrack 0 May 20 16:22:22 volumio volumio[957]: info: CoreCommandRouter::volumioPushState May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 389ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 359ms May 20 16:22:22 volumio volumio[957]: info: ------------------------------ 355ms May 20 16:22:28 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 20 16:22:28 volumio volumio[957]: info: CURURI: genres:// May 20 16:22:28 volumio volumio[957]: info: Preload queue cleared May 20 16:22:35 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 20 16:22:35 volumio volumio[957]: info: Preload queue cleared May 20 16:22:47 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 20 16:22:47 volumio volumio[957]: info: CURURI: albums:// May 20 16:22:47 volumio volumio[957]: info: listAlbums - loading Albums from cache May 20 16:22:47 volumio volumio[957]: info: Preload queue cleared May 20 16:23:10 volumio volumio[957]: error: error May 20 16:23:10 volumio volumio[957]: error: error May 20 16:23:15 volumio volumio[957]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 16:23:15 volumio volumio[957]: Error: connect ETIMEDOUT 69.171.242.11:80 May 20 16:23:15 volumio volumio[957]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 20 16:23:15 volumio volumio[957]: errno: -110, May 20 16:23:15 volumio volumio[957]: code: 'ETIMEDOUT', May 20 16:23:15 volumio volumio[957]: syscall: 'connect', May 20 16:23:15 volumio volumio[957]: address: '69.171.242.11', May 20 16:23:15 volumio volumio[957]: port: 80 May 20 16:23:15 volumio volumio[957]: } May 20 16:23:15 volumio volumio[957]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 16:23:16 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 16:22 May 20 16:23:16 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"