-- Logs begin at Wed 2025-03-19 12:28:21 CST, end at Wed 2025-03-19 12:33:13 CST. -- Mar 19 12:32:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Mar 19 12:32:00 volumio volumio[1190]: info: Adding a new share Mar 19 12:32:00 volumio volumio[1190]: info: Share alias is blank Mar 19 12:32:02 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:06 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:08 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 19 12:32:08 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 19 12:32:10 volumio volumio[1190]: info: Error browsing device DH2600-8E9C for shares : Error: Command failed: echo volumio | smbclient -L DH2600-8E9C.local. Mar 19 12:32:10 volumio volumio[1190]: Unable to initialize messaging context Mar 19 12:32:10 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:14 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:18 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:22 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:30 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:34 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:38 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:40 volumio volumio[1190]: info: Clearing queue after UPNP request Mar 19 12:32:40 volumio volumio[1190]: info: CoreStateMachine::ClearQueue Mar 19 12:32:40 volumio volumio[1190]: info: CoreStateMachine::stop Mar 19 12:32:40 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 12:32:40 volumio volumio[1190]: info: CorePlayQueue::clearPlayQueue Mar 19 12:32:40 volumio volumio[1190]: info: CorePlayQueue::saveQueue Mar 19 12:32:40 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:40 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Mar 19 12:32:40 volumio volumio[1190]: error: Upnp client error: Error: This socket has been ended by the other party Mar 19 12:32:42 volumio volumio[1190]: info: Starting UPNP Playback Mar 19 12:32:42 volumio volumio[1190]: info: Preparing playback through UPNP Mar 19 12:32:42 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 19 12:32:42 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Mar 19 12:32:42 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 12:32:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 18ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 14ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 12ms Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 11ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 9ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 7ms Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 12ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 9ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 7ms Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 9ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 7ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 4ms Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 7ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 6ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 4ms Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: Mar 19 12:32:42 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:42 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 8ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 6ms Mar 19 12:32:42 volumio volumio[1190]: info: ------------------------------ 4ms Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio kernel: usb 1-1.4.1: current rate 44032 is different from the runtime rate 44100 Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 7 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 6 milliseconds Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:44 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 14 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 12 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 7 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 5 milliseconds Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 47ms Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 57ms Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:44 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Mar 19 12:32:44 volumio volumio[1190]: info: Ignoring MPD Status Update Mar 19 12:32:44 volumio volumio[1190]: info: Mar 19 12:32:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::getState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 49ms Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 47 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 44 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 8ms Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 7 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 7ms Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand status took 5 milliseconds Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 152ms Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 151ms Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 144ms Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 143ms Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 110 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 108 milliseconds Mar 19 12:32:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 108 milliseconds Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ControllerMpd::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Mar 19 12:32:44 volumio volumio[1190]: verbose: In UPNP mode Mar 19 12:32:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":123,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Here is Your Perfect","artist":"Ebmusic","album":"Here is Your Perfect","uri":"http://aqqmusic.tc.qq.com/M500003i9Uu13pttBH.mp3?guid=ffffffffaab7ee7700000192cb33fff7&vkey=CF25B8A5935449312610BC3680B1251A05339A196AD2FEE4C9DAA3D1EAC032CF6290E00B4865A4A1F145DA014866FF2C7896DD32E56FD2C8__v2b94c6a4&uin=9852603&redirect=1&fromtag=111042","trackType":""} Mar 19 12:32:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Mar 19 12:32:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::pushState Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 235ms Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 195ms Mar 19 12:32:44 volumio volumio[1190]: info: ------------------------------ 194ms Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:44 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:44 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:46 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 12:32:51 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Mar 19 12:32:55 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Mar 19 12:32:55 volumio volumio[1190]: info: CoreStateMachine::getQueue Mar 19 12:32:55 volumio volumio[1190]: info: CorePlayQueue::getQueue Mar 19 12:32:58 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 19 12:32:58 volumio volumio[1190]: info: CURURI: playlists Mar 19 12:32:58 volumio volumio[1190]: info: Listing playlists Mar 19 12:32:58 volumio volumio[1190]: info: Preload queue cleared Mar 19 12:33:01 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 19 12:33:01 volumio volumio[1190]: info: CURURI: music-library Mar 19 12:33:01 volumio volumio[1190]: error: Failed LSINFO: null Mar 19 12:33:01 volumio volumio[1190]: info: Preload queue cleared Mar 19 12:33:08 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 19 12:33:08 volumio volumio[1190]: info: Preload queue cleared Mar 19 12:33:10 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 19 12:33:10 volumio volumio[1190]: info: Preload queue cleared Mar 19 12:33:11 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 19 12:33:12 volumio volumio[1190]: info: Preload queue cleared Mar 19 12:33:13 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 19 12:33:13 volumio volumio[1190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 12:33:13 volumio volumio[1190]: TypeError: Cannot read property 'length' of undefined Mar 19 12:33:13 volumio volumio[1190]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 19 12:33:13 volumio volumio[1190]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 19 12:33:13 volumio volumio[1190]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 19 12:33:13 volumio volumio[1190]: at Parser.emit (events.js:400:28) Mar 19 12:33:13 volumio volumio[1190]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 19 12:33:13 volumio volumio[1190]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 19 12:33:13 volumio volumio[1190]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 19 12:33:13 volumio volumio[1190]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 19 12:33:13 volumio volumio[1190]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 19 12:33:13 volumio volumio[1190]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 19 12:33:13 volumio volumio[1190]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 19 12:33:13 volumio volumio[1190]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 19 12:33:13 volumio volumio[1190]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 19 12:33:13 volumio volumio[1190]: at IncomingMessage.emit (events.js:412:35) Mar 19 12:33:13 volumio volumio[1190]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 19 12:33:13 volumio volumio[1190]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 19 12:33:13 volumio volumio[1190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 12:33:13 volumio sudo[2818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-19 12:32 Mar 19 12:33:13 volumio sudo[2818]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"