-- Logs begin at Thu 2024-04-04 13:31:35 UTC, end at Thu 2024-04-04 14:12:51 UTC. -- Apr 04 14:11:04 volumio volumio[6052]: info: Apr 04 14:11:04 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:04 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:04 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:04 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:11:04 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:04 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:04 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:11:04 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:04 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:04 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:04 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:04 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:04 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:04 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:11:04 volumio volumio[6052]: 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":"REACH THE SKY","artist":"余景天","album":"REACH THE SKY","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500002HrFS54cd39J.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=427AB8783590DABA8FD68FE5393361CABAD51FFA8EA6B562D8E1FB24562CFEF3E5B821C2C262962CE085EDBC18C66DFF4D1DE1659F8B1394&uin=22213939&src=M500004eA3Pl2iESLM.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:04 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:04 volumio volumio[6052]: info: CoreStateMachine::syncState stateService stop Apr 04 14:11:04 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:04 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:04 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:04 volumio volumio[6052]: info: CoreStateMachine::stPlaybackTimer Apr 04 14:11:04 volumio volumio[6052]: info: ------------------------------ 10ms Apr 04 14:11:04 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:04 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:04 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:12 volumio volumio[6052]: info: Clearing queue after UPNP request Apr 04 14:11:13 volumio volumio[6052]: info: CoreStateMachine::ClearQueue Apr 04 14:11:13 volumio volumio[6052]: info: CoreStateMachine::stop Apr 04 14:11:13 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 14:11:13 volumio volumio[6052]: verbose: UNSET VOLATILE Apr 04 14:11:13 volumio volumio[6052]: info: CorePlayQueue::clearPlayQueue Apr 04 14:11:13 volumio volumio[6052]: info: CorePlayQueue::saveQueue Apr 04 14:11:13 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:13 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:13 volumio volumio[6052]: info: CoreCommandRouter::volumioPushQueue Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: error: updateQueue error: null Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:11:13 volumio volumio[6052]: info: Starting UPNP Playback Apr 04 14:11:13 volumio volumio[6052]: info: Preparing playback through UPNP Apr 04 14:11:13 volumio volumio[6052]: info: CoreCommandRouter::volumioGetState Apr 04 14:11:13 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:11:13 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 0ms Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 0ms Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: info: Apr 04 14:11:13 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:13 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 3ms Apr 04 14:11:13 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:14 volumio volumio[6052]: info: Apr 04 14:11:14 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:14 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:14 volumio volumio[6052]: info: Apr 04 14:11:14 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:14 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:14 volumio volumio[6052]: info: Apr 04 14:11:14 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:14 volumio volumio[6052]: info: sendMpdCommand status took 3 milliseconds Apr 04 14:11:14 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:14 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:11:14 volumio volumio[6052]: info: sendMpdCommand status took 4 milliseconds Apr 04 14:11:14 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:14 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:14 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:14 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"See You Again(ABGT468)","artist":"Seven Lions","album":"Group Therapy 468","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500000bZPdr3BYJxq.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=FFC00AA2A1AF9B842A0CA66160B2F33E9592DB32C71FE407AC2F9BCB034AC504F5F1D2458C80A9ACF54B4BDEFF8EAD17BA988216DB527BED&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:14 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus stop Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:14 volumio volumio[6052]: info: ------------------------------ 13ms Apr 04 14:11:14 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 04 14:11:14 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:14 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:14 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:14 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"See You Again(ABGT468)","artist":"Seven Lions","album":"Group Therapy 468","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500000bZPdr3BYJxq.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=FFC00AA2A1AF9B842A0CA66160B2F33E9592DB32C71FE407AC2F9BCB034AC504F5F1D2458C80A9ACF54B4BDEFF8EAD17BA988216DB527BED&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:14 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:14 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:14 volumio volumio[6052]: info: ------------------------------ 35ms Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:14 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:14 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:14 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:14 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:14 volumio volumio[6052]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BD%99%E6%99%AF%E5%A4%A9/REACH%20THE%20SKY/beeefa14-f8e2-4393-a52b-853d4d06d304.jpg' Apr 04 14:11:23 volumio volumio[6052]: info: Apr 04 14:11:23 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:23 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:23 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:23 volumio volumio[6052]: info: sendMpdCommand status took 2 milliseconds Apr 04 14:11:23 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:23 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:23 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 04 14:11:23 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:23 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:23 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:23 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33000,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"See You Again(ABGT468)","artist":"Seven Lions","album":"Group Therapy 468","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500000bZPdr3BYJxq.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=FFC00AA2A1AF9B842A0CA66160B2F33E9592DB32C71FE407AC2F9BCB034AC504F5F1D2458C80A9ACF54B4BDEFF8EAD17BA988216DB527BED&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:23 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:23 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:23 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:23 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:11:23 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:23 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:23 volumio volumio[6052]: info: ------------------------------ 43ms Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:23 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:23 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:23 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:23 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:23 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:26 volumio volumio[6052]: info: Apr 04 14:11:26 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:26 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:26 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:26 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:11:26 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:26 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:26 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:11:26 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:26 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:26 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:26 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53000,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"See You Again(ABGT468)","artist":"Seven Lions","album":"Group Therapy 468","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500000bZPdr3BYJxq.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=FFC00AA2A1AF9B842A0CA66160B2F33E9592DB32C71FE407AC2F9BCB034AC504F5F1D2458C80A9ACF54B4BDEFF8EAD17BA988216DB527BED&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:26 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:26 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:26 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:26 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:11:26 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:26 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:26 volumio volumio[6052]: info: ------------------------------ 31ms Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:26 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:26 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:26 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:26 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:26 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:33 volumio volumio[6052]: info: Apr 04 14:11:33 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:33 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:33 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:33 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:11:33 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:33 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:33 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:11:33 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:33 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:33 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:33 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:33 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:33 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:33 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:11:33 volumio volumio[6052]: 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":"See You Again(ABGT468)","artist":"Seven Lions","album":"Group Therapy 468","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500000bZPdr3BYJxq.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=FFC00AA2A1AF9B842A0CA66160B2F33E9592DB32C71FE407AC2F9BCB034AC504F5F1D2458C80A9ACF54B4BDEFF8EAD17BA988216DB527BED&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:33 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:33 volumio volumio[6052]: info: CoreStateMachine::syncState stateService stop Apr 04 14:11:33 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:33 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:33 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:33 volumio volumio[6052]: info: CoreStateMachine::stPlaybackTimer Apr 04 14:11:33 volumio volumio[6052]: info: ------------------------------ 13ms Apr 04 14:11:33 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:33 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:33 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:38 volumio volumio[6052]: info: Clearing queue after UPNP request Apr 04 14:11:38 volumio volumio[6052]: info: CoreStateMachine::ClearQueue Apr 04 14:11:38 volumio volumio[6052]: info: CoreStateMachine::stop Apr 04 14:11:38 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 14:11:38 volumio volumio[6052]: verbose: UNSET VOLATILE Apr 04 14:11:38 volumio volumio[6052]: info: CorePlayQueue::clearPlayQueue Apr 04 14:11:38 volumio volumio[6052]: info: CorePlayQueue::saveQueue Apr 04 14:11:38 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:38 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:38 volumio volumio[6052]: info: CoreCommandRouter::volumioPushQueue Apr 04 14:11:38 volumio volumio[6052]: info: Apr 04 14:11:38 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:38 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:38 volumio volumio[6052]: error: updateQueue error: null Apr 04 14:11:38 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:38 volumio volumio[6052]: info: Starting UPNP Playback Apr 04 14:11:38 volumio volumio[6052]: info: Preparing playback through UPNP Apr 04 14:11:38 volumio volumio[6052]: info: CoreCommandRouter::volumioGetState Apr 04 14:11:38 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:11:38 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:39 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:39 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:39 volumio volumio[6052]: info: Apr 04 14:11:39 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:39 volumio volumio[6052]: info: sendMpdCommand status took 6 milliseconds Apr 04 14:11:39 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 6ms Apr 04 14:11:39 volumio volumio[6052]: info: sendMpdCommand status took 6 milliseconds Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:39 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 04 14:11:39 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:39 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:39 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:39 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:39 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sunset","artist":"A7i","album":"Summer 2022","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M5000014Lut022fwka.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=A7675A277F13DDF484428EFA86A517F19857546D20104D08CD838232C9B76DF047589DF0EBA7F4481DC79EBB5EBF3422DF2B917BFDB1B2BA&uin=22213939&src=M500000vxU4h165aAt.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:39 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus stop Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:39 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:39 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:39 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Sunset","artist":"A7i","album":"Summer 2022","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M5000014Lut022fwka.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=A7675A277F13DDF484428EFA86A517F19857546D20104D08CD838232C9B76DF047589DF0EBA7F4481DC79EBB5EBF3422DF2B917BFDB1B2BA&uin=22213939&src=M500000vxU4h165aAt.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:39 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:39 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 42ms Apr 04 14:11:39 volumio volumio[6052]: info: ------------------------------ 36ms Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:39 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:39 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:39 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:39 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:45 volumio volumio[6052]: info: Apr 04 14:11:45 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:45 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:45 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:45 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:11:45 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:45 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:45 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:11:45 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:45 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:45 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:11:45 volumio volumio[6052]: 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":"Sunset","artist":"A7i","album":"Summer 2022","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M5000014Lut022fwka.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=A7675A277F13DDF484428EFA86A517F19857546D20104D08CD838232C9B76DF047589DF0EBA7F4481DC79EBB5EBF3422DF2B917BFDB1B2BA&uin=22213939&src=M500000vxU4h165aAt.mp3&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:45 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::syncState stateService stop Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::stPlaybackTimer Apr 04 14:11:45 volumio volumio[6052]: info: ------------------------------ 17ms Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:45 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:45 volumio volumio[6052]: info: Clearing queue after UPNP request Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::ClearQueue Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::stop Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 14:11:45 volumio volumio[6052]: verbose: UNSET VOLATILE Apr 04 14:11:45 volumio volumio[6052]: info: CorePlayQueue::clearPlayQueue Apr 04 14:11:45 volumio volumio[6052]: info: CorePlayQueue::saveQueue Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::volumioPushQueue Apr 04 14:11:45 volumio volumio[6052]: info: Apr 04 14:11:45 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:45 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:45 volumio volumio[6052]: error: updateQueue error: null Apr 04 14:11:45 volumio volumio[6052]: info: ------------------------------ 4ms Apr 04 14:11:45 volumio volumio[6052]: info: Starting UPNP Playback Apr 04 14:11:45 volumio volumio[6052]: info: Preparing playback through UPNP Apr 04 14:11:45 volumio volumio[6052]: info: CoreCommandRouter::volumioGetState Apr 04 14:11:45 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:11:45 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:46 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:46 volumio volumio[6052]: info: sendMpdCommand status took 4 milliseconds Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:11:46 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:11:46 volumio volumio[6052]: info: Apr 04 14:11:46 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:11:46 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:11:46 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 04 14:11:46 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:11:46 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:46 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:46 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只记今朝笑","artist":"林青霞","album":"东方不败-林青霞 电影原声带","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500001Gdc1P2gg8x9.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=D53ED98F5905E8C651A1BBF5757A7423B9AB370D74B1036E5B50BB9A6CA450F4F0CD41FCE53CE38B442D590A5FA88A28A048C118B8427971&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:46 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus stop Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 26ms Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 20ms Apr 04 14:11:46 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 04 14:11:46 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:11:46 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:11:46 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:11:46 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只记今朝笑","artist":"林青霞","album":"东方不败-林青霞 电影原声带","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500001Gdc1P2gg8x9.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=D53ED98F5905E8C651A1BBF5757A7423B9AB370D74B1036E5B50BB9A6CA450F4F0CD41FCE53CE38B442D590A5FA88A28A048C118B8427971&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:11:46 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:11:46 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:11:46 volumio volumio[6052]: info: ------------------------------ 43ms Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:46 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:11:46 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:11:46 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:11:46 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:03 volumio volumio[6052]: info: Apr 04 14:12:03 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:03 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:03 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:03 volumio volumio[6052]: info: sendMpdCommand status took 3 milliseconds Apr 04 14:12:03 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:03 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:03 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:12:03 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:03 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:03 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:03 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46000,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只记今朝笑","artist":"林青霞","album":"东方不败-林青霞 电影原声带","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500001Gdc1P2gg8x9.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=D53ED98F5905E8C651A1BBF5757A7423B9AB370D74B1036E5B50BB9A6CA450F4F0CD41FCE53CE38B442D590A5FA88A28A048C118B8427971&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:03 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:03 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:03 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:03 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:03 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:03 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:03 volumio volumio[6052]: info: ------------------------------ 27ms Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:03 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:03 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:03 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:03 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:03 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:04 volumio volumio[6052]: info: Apr 04 14:12:04 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:04 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:04 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:04 volumio volumio[6052]: info: sendMpdCommand status took 5 milliseconds Apr 04 14:12:04 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:04 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:04 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 04 14:12:04 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:04 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:04 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:04 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":77000,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"只记今朝笑","artist":"林青霞","album":"东方不败-林青霞 电影原声带","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500001Gdc1P2gg8x9.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=D53ED98F5905E8C651A1BBF5757A7423B9AB370D74B1036E5B50BB9A6CA450F4F0CD41FCE53CE38B442D590A5FA88A28A048C118B8427971&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:04 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:04 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:04 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:04 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:04 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:04 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:04 volumio volumio[6052]: info: ------------------------------ 26ms Apr 04 14:12:04 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:05 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:05 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:05 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:05 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:05 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:08 volumio volumio[6052]: info: Apr 04 14:12:08 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:08 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:08 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:08 volumio volumio[6052]: info: sendMpdCommand status took 0 milliseconds Apr 04 14:12:08 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:08 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:08 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 04 14:12:08 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:08 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:08 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:12:08 volumio volumio[6052]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只记今朝笑","artist":"林青霞","album":"东方不败-林青霞 电影原声带","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500001Gdc1P2gg8x9.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=D53ED98F5905E8C651A1BBF5757A7423B9AB370D74B1036E5B50BB9A6CA450F4F0CD41FCE53CE38B442D590A5FA88A28A048C118B8427971&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:08 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::syncState stateService stop Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::stPlaybackTimer Apr 04 14:12:08 volumio volumio[6052]: info: ------------------------------ 8ms Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:08 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:08 volumio volumio[6052]: info: Clearing queue after UPNP request Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::ClearQueue Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::stop Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 14:12:08 volumio volumio[6052]: verbose: UNSET VOLATILE Apr 04 14:12:08 volumio volumio[6052]: info: CorePlayQueue::clearPlayQueue Apr 04 14:12:08 volumio volumio[6052]: info: CorePlayQueue::saveQueue Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::volumioPushQueue Apr 04 14:12:08 volumio volumio[6052]: info: Apr 04 14:12:08 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:08 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:08 volumio volumio[6052]: info: Starting UPNP Playback Apr 04 14:12:08 volumio volumio[6052]: info: Preparing playback through UPNP Apr 04 14:12:08 volumio volumio[6052]: info: CoreCommandRouter::volumioGetState Apr 04 14:12:08 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:12:08 volumio volumio[6052]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 04 14:12:08 volumio volumio[6052]: error: updateQueue error: null Apr 04 14:12:08 volumio volumio[6052]: info: ------------------------------ 7ms Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 2ms Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 1ms Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand status took 2 milliseconds Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces system playlist update Apr 04 14:12:09 volumio volumio[6052]: info: Ignoring MPD Status Update Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand status took 7 milliseconds Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:09 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:09 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:09 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus stop Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 17ms Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 13ms Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand status took 14 milliseconds Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:09 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:09 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:09 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:09 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 52ms Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:09 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:09 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:09 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:09 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 62ms Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: Apr 04 14:12:09 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand status took 4 milliseconds Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:09 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:12:09 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:09 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:09 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:09 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42000,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:09 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:09 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:09 volumio volumio[6052]: info: ------------------------------ 23ms Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:09 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:09 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:14 volumio volumio[6052]: info: Apr 04 14:12:14 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:14 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:14 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:14 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:12:14 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:14 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:14 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:12:14 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:14 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:14 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:14 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20000,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:14 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:14 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:14 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:14 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:14 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:14 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:14 volumio volumio[6052]: info: ------------------------------ 52ms Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:14 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:14 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:14 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:14 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:14 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:16 volumio volumio[6052]: info: Apr 04 14:12:16 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:16 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:16 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:16 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:12:16 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:16 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:16 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:12:16 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:16 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:16 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:16 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104000,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:16 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:16 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:16 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:16 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:16 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:16 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:16 volumio volumio[6052]: info: ------------------------------ 16ms Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:16 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:16 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:16 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:16 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:16 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:40 volumio volumio[6052]: info: Apr 04 14:12:40 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:40 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:40 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:40 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:12:40 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:40 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:40 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:12:40 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:40 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:40 volumio volumio[6052]: verbose: In UPNP mode Apr 04 14:12:40 volumio volumio[6052]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143000,"duration":178,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:40 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:40 volumio volumio[6052]: info: CoreStateMachine::syncState stateService play Apr 04 14:12:40 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:40 volumio volumio[6052]: info: Received an update from plugin. extracting info from payload Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 04 14:12:40 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:40 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:40 volumio volumio[6052]: info: ------------------------------ 13ms Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:40 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:40 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:40 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:40 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:40 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:44 volumio volumio[6052]: info: Apr 04 14:12:44 volumio volumio[6052]: ---------------------------- MPD announces state update: player Apr 04 14:12:44 volumio volumio[6052]: info: ControllerMpd::getState Apr 04 14:12:44 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand status Apr 04 14:12:44 volumio volumio[6052]: info: sendMpdCommand status took 1 milliseconds Apr 04 14:12:44 volumio volumio[6052]: verbose: ControllerMpd::parseState Apr 04 14:12:44 volumio volumio[6052]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 14:12:44 volumio volumio[6052]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 14:12:44 volumio volumio[6052]: verbose: ControllerMpd::parseTrackInfo Apr 04 14:12:44 volumio volumio[6052]: info: ControllerMpd::pushState Apr 04 14:12:44 volumio volumio[6052]: info: CoreCommandRouter::servicePushState Apr 04 14:12:44 volumio volumio[6052]: info: CoreStateMachine::pushState Apr 04 14:12:44 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:44 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:44 volumio volumio[6052]: info: CorePlayQueue::getTrack 0 Apr 04 14:12:44 volumio volumio[6052]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"沧海一声笑","artist":"许冠杰","album":"打工仔心声98","uri":"http://60.221.71.199/amobile.music.tc.qq.com/M500004V8dUG0RgKIg.mp3?guid=ffffffffbe7698bc000001883e33e7c7&vkey=E4FC40FD119DF7638BDBF55C1707BD5CD1FEEEDA2F29C65A846620B3C64FF0161B1C1F12271B9C79E93A843F4A962842A3501FC0039D3801&uin=22213939&redirect=1&fromtag=111042","trackType":""} Apr 04 14:12:44 volumio volumio[6052]: verbose: CURRENT POSITION 0 Apr 04 14:12:44 volumio volumio[6052]: info: CoreStateMachine::syncState stateService stop Apr 04 14:12:44 volumio volumio[6052]: info: CoreStateMachine::syncState currentStatus play Apr 04 14:12:44 volumio volumio[6052]: info: CoreCommandRouter::volumioPushState Apr 04 14:12:44 volumio volumio[6052]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 14:12:44 volumio volumio[6052]: info: CoreStateMachine::stPlaybackTimer Apr 04 14:12:44 volumio volumio[6052]: info: ------------------------------ 10ms Apr 04 14:12:44 volumio volumio[6052]: info: CoreCommandRouter::volumioGetQueue Apr 04 14:12:44 volumio volumio[6052]: info: CoreStateMachine::getQueue Apr 04 14:12:44 volumio volumio[6052]: info: CorePlayQueue::getQueue Apr 04 14:12:50 volumio volumio[6052]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 14:12:50 volumio volumio[6052]: { Error: connect ETIMEDOUT 199.59.148.201:443 Apr 04 14:12:50 volumio volumio[6052]: at Object._errnoException (util.js:1022:11) Apr 04 14:12:50 volumio volumio[6052]: at _exceptionWithHostPort (util.js:1044:20) Apr 04 14:12:50 volumio volumio[6052]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 04 14:12:50 volumio volumio[6052]: code: 'ETIMEDOUT', Apr 04 14:12:50 volumio volumio[6052]: errno: 'ETIMEDOUT', Apr 04 14:12:50 volumio volumio[6052]: syscall: 'connect', Apr 04 14:12:50 volumio volumio[6052]: address: '199.59.148.201', Apr 04 14:12:50 volumio volumio[6052]: port: 443 } Apr 04 14:12:50 volumio volumio[6052]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 14:12:51 volumio sudo[8771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-04 14:11 Apr 04 14:12:51 volumio sudo[8771]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"