-- Logs begin at Fri 2024-04-05 08:55:47 UTC, end at Fri 2024-04-05 13:01:46 UTC. -- Apr 05 13:00:00 volumio volumio[9806]: info: Apr 05 13:00:00 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:00:00 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:00:00 volumio volumio[9806]: info: Apr 05 13:00:00 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:00 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:00 volumio volumio[9806]: info: Apr 05 13:00:00 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:00:00 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:00:00 volumio volumio[9806]: info: ------------------------------ 4ms Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:00:00 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:00:00 volumio volumio[9806]: In UPNP mode Apr 05 13:00:00 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":487,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Cadenzas","artist":"Matt Haimovitz","album":"Sunrise Falling","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M800003shUd84F3onc.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=A1C95E47A3021BBDAF85C76273D6147CDA4590A586B4CE571C66F1E66662ECC9C2108CF91698CA7699E457C4680B3103D179966DC8FA1578&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:00:00 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus stop Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:00 volumio volumio[9806]: info: ------------------------------ 416ms Apr 05 13:00:00 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:00:00 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:00:00 volumio volumio[9806]: In UPNP mode Apr 05 13:00:00 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":487,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Cadenzas","artist":"Matt Haimovitz","album":"Sunrise Falling","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M800003shUd84F3onc.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=A1C95E47A3021BBDAF85C76273D6147CDA4590A586B4CE571C66F1E66662ECC9C2108CF91698CA7699E457C4680B3103D179966DC8FA1578&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:00:00 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:00:00 volumio volumio[9806]: info: Received an update from plugin. extracting info from payload Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:00 volumio volumio[9806]: info: ------------------------------ 24ms Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:00:00 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:00:00 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:00:00 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:00:00 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:00:28 volumio volumio[9806]: info: Apr 05 13:00:28 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:00:28 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:00:28 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:00:28 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:00:28 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:00:28 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:00:28 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:00:28 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:00:28 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:28 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:28 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:28 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:00:28 volumio volumio[9806]: 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":"Cadenzas","artist":"Matt Haimovitz","album":"Sunrise Falling","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M800003shUd84F3onc.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=A1C95E47A3021BBDAF85C76273D6147CDA4590A586B4CE571C66F1E66662ECC9C2108CF91698CA7699E457C4680B3103D179966DC8FA1578&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:00:28 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:00:28 volumio volumio[9806]: info: CoreStateMachine::syncState stateService stop Apr 05 13:00:28 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:00:28 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:28 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:28 volumio volumio[9806]: info: CoreStateMachine::stPlaybackTimer Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 36ms Apr 05 13:00:29 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:00:29 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:00:29 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:00:29 volumio volumio[9806]: info: Clearing queue after UPNP request Apr 05 13:00:29 volumio volumio[9806]: info: CoreStateMachine::ClearQueue Apr 05 13:00:29 volumio volumio[9806]: info: CoreStateMachine::stop Apr 05 13:00:29 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 13:00:29 volumio volumio[9806]: UNSET VOLATILE Apr 05 13:00:29 volumio volumio[9806]: info: CorePlayQueue::clearPlayQueue Apr 05 13:00:29 volumio volumio[9806]: info: CorePlayQueue::saveQueue Apr 05 13:00:29 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:29 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:29 volumio volumio[9806]: info: CoreCommandRouter::volumioPushQueue Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: error: updateQueue error: null Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:00:29 volumio volumio[9806]: info: Starting UPNP Playback Apr 05 13:00:29 volumio volumio[9806]: info: Preparing playback through UPNP Apr 05 13:00:29 volumio volumio[9806]: info: CoreCommandRouter::volumioGetState Apr 05 13:00:29 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:00:29 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:00:29 volumio volumio[9806]: info: Apr 05 13:00:29 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:29 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:29 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:00:30 volumio volumio[9806]: info: Apr 05 13:00:30 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:00:30 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:00:30 volumio volumio[9806]: info: Apr 05 13:00:30 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:00:30 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:00:30 volumio volumio[9806]: info: Apr 05 13:00:30 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:00:30 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:00:30 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:00:30 volumio volumio[9806]: In UPNP mode Apr 05 13:00:30 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一剪梅","artist":"刘嘉佳","album":"一剪梅","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M8000048IWMB0UPJXC.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=01A5125C5DADCD4CEF32AA4754EA21003E9137D62E934B9E3A4C303A6B1718E1910189459B893EC2101AAE31CCF4D0628024DB0626495CC5&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:00:30 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus stop Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:30 volumio volumio[9806]: info: ------------------------------ 418ms Apr 05 13:00:30 volumio volumio[9806]: info: ------------------------------ 19ms Apr 05 13:00:30 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:00:30 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:00:30 volumio volumio[9806]: In UPNP mode Apr 05 13:00:30 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一剪梅","artist":"刘嘉佳","album":"一剪梅","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M8000048IWMB0UPJXC.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=01A5125C5DADCD4CEF32AA4754EA21003E9137D62E934B9E3A4C303A6B1718E1910189459B893EC2101AAE31CCF4D0628024DB0626495CC5&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:00:30 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:00:30 volumio volumio[9806]: info: Received an update from plugin. extracting info from payload Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:00:30 volumio volumio[9806]: info: ------------------------------ 42ms Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:00:30 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:00:30 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:00:30 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:00:30 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:31 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:31 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:31 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:31 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:31 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:31 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:31 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:01:31 volumio volumio[9806]: 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://183.240.185.13/amobile.music.tc.qq.com/M8000048IWMB0UPJXC.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=01A5125C5DADCD4CEF32AA4754EA21003E9137D62E934B9E3A4C303A6B1718E1910189459B893EC2101AAE31CCF4D0628024DB0626495CC5&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:31 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::syncState stateService stop Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::stPlaybackTimer Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 32ms Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:31 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:31 volumio volumio[9806]: info: Clearing queue after UPNP request Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::ClearQueue Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::stop Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 13:01:31 volumio volumio[9806]: UNSET VOLATILE Apr 05 13:01:31 volumio volumio[9806]: info: CorePlayQueue::clearPlayQueue Apr 05 13:01:31 volumio volumio[9806]: info: CorePlayQueue::saveQueue Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::volumioPushQueue Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: Starting UPNP Playback Apr 05 13:01:31 volumio volumio[9806]: info: Preparing playback through UPNP Apr 05 13:01:31 volumio volumio[9806]: info: CoreCommandRouter::volumioGetState Apr 05 13:01:31 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:01:31 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 13:01:31 volumio volumio[9806]: error: updateQueue error: null Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 10ms Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 3ms Apr 05 13:01:31 volumio volumio[9806]: info: Apr 05 13:01:31 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:31 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:31 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:32 volumio volumio[9806]: info: Apr 05 13:01:32 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:32 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:32 volumio volumio[9806]: info: Apr 05 13:01:32 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:32 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:32 volumio volumio[9806]: info: Apr 05 13:01:32 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:32 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:32 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:32 volumio volumio[9806]: In UPNP mode Apr 05 13:01:32 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聆听中国之春江花月夜","artist":"赵聪","album":"试音宝典 试机六号","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M800000kMUiI2LXPQQ.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=44ED0818D609B64074FA84C35E87FB4067D747E6E9392AEA7938D94B2714DE89FE4DDB349762B71518EA65F84E33149D2D2114E0A373D319&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:32 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus stop Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:32 volumio volumio[9806]: info: ------------------------------ 414ms Apr 05 13:01:32 volumio volumio[9806]: info: ------------------------------ 17ms Apr 05 13:01:32 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:32 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:32 volumio volumio[9806]: In UPNP mode Apr 05 13:01:32 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"聆听中国之春江花月夜","artist":"赵聪","album":"试音宝典 试机六号","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M800000kMUiI2LXPQQ.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=44ED0818D609B64074FA84C35E87FB4067D747E6E9392AEA7938D94B2714DE89FE4DDB349762B71518EA65F84E33149D2D2114E0A373D319&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:32 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:01:32 volumio volumio[9806]: info: Received an update from plugin. extracting info from payload Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:32 volumio volumio[9806]: info: ------------------------------ 33ms Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:32 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:32 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:32 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:32 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:38 volumio volumio[9806]: info: Apr 05 13:01:38 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:38 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:38 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:38 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:38 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:38 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:38 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:38 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:01:38 volumio volumio[9806]: 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://183.240.185.13/amobile.music.tc.qq.com/M800000kMUiI2LXPQQ.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=44ED0818D609B64074FA84C35E87FB4067D747E6E9392AEA7938D94B2714DE89FE4DDB349762B71518EA65F84E33149D2D2114E0A373D319&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:38 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::syncState stateService stop Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::stPlaybackTimer Apr 05 13:01:38 volumio volumio[9806]: info: ------------------------------ 14ms Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:38 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:38 volumio volumio[9806]: info: Clearing queue after UPNP request Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::ClearQueue Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::stop Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 13:01:38 volumio volumio[9806]: UNSET VOLATILE Apr 05 13:01:38 volumio volumio[9806]: info: CorePlayQueue::clearPlayQueue Apr 05 13:01:38 volumio volumio[9806]: info: CorePlayQueue::saveQueue Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::volumioPushQueue Apr 05 13:01:38 volumio volumio[9806]: info: Apr 05 13:01:38 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:38 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:38 volumio volumio[9806]: error: updateQueue error: null Apr 05 13:01:38 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:38 volumio volumio[9806]: info: Starting UPNP Playback Apr 05 13:01:38 volumio volumio[9806]: info: Preparing playback through UPNP Apr 05 13:01:38 volumio volumio[9806]: info: CoreCommandRouter::volumioGetState Apr 05 13:01:38 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:01:38 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:39 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:39 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:39 volumio volumio[9806]: info: ------------------------------ 9ms Apr 05 13:01:39 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:39 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:39 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:39 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:39 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:39 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:39 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:39 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:39 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:39 volumio volumio[9806]: info: Apr 05 13:01:39 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:39 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:39 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:40 volumio volumio[9806]: info: Apr 05 13:01:40 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:40 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:40 volumio volumio[9806]: info: Apr 05 13:01:40 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:40 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:40 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:40 volumio volumio[9806]: In UPNP mode Apr 05 13:01:40 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"随想曲","artist":"童丽","album":"每一个晚上","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M8000046SjIP3HiK5C.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=8F17DE5FD60EAEFC2FA19FB496499EBF2473F886C03F5D24CF2A1D65B57E681627B0D89E0E0D75A5F0D54023DD7DC1D6EBC212E384B0DC39&uin=1259438298&src=M800001hJYoW0PIJ3g.mp3&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:40 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus stop Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:40 volumio volumio[9806]: info: ------------------------------ 423ms Apr 05 13:01:40 volumio volumio[9806]: info: ------------------------------ 21ms Apr 05 13:01:40 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:40 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:40 volumio volumio[9806]: In UPNP mode Apr 05 13:01:40 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"随想曲","artist":"童丽","album":"每一个晚上","uri":"http://183.240.185.13/amobile.music.tc.qq.com/M8000046SjIP3HiK5C.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=8F17DE5FD60EAEFC2FA19FB496499EBF2473F886C03F5D24CF2A1D65B57E681627B0D89E0E0D75A5F0D54023DD7DC1D6EBC212E384B0DC39&uin=1259438298&src=M800001hJYoW0PIJ3g.mp3&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:40 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:01:40 volumio volumio[9806]: info: Received an update from plugin. extracting info from payload Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:40 volumio volumio[9806]: info: ------------------------------ 72ms Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:40 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:40 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:40 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:40 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:40 volumio volumio[9806]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E4%B8%BD/%E6%AF%8F%E4%B8%80%E4%B8%AA%E6%99%9A%E4%B8%8A/d5221ecc-2fa9-4468-a7b8-5c96a9bfe69b.jpg' Apr 05 13:01:41 volumio volumio[9806]: info: Apr 05 13:01:41 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:41 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:41 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:41 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:41 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:41 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:41 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:41 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:41 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:41 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:41 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:41 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:01:41 volumio volumio[9806]: 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://183.240.185.13/amobile.music.tc.qq.com/M8000046SjIP3HiK5C.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=8F17DE5FD60EAEFC2FA19FB496499EBF2473F886C03F5D24CF2A1D65B57E681627B0D89E0E0D75A5F0D54023DD7DC1D6EBC212E384B0DC39&uin=1259438298&src=M800001hJYoW0PIJ3g.mp3&redirect=1&fromtag=111093","trackType":""} Apr 05 13:01:41 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:41 volumio volumio[9806]: info: CoreStateMachine::syncState stateService stop Apr 05 13:01:41 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:01:41 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:41 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:41 volumio volumio[9806]: info: CoreStateMachine::stPlaybackTimer Apr 05 13:01:41 volumio volumio[9806]: info: ------------------------------ 24ms Apr 05 13:01:41 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:41 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:41 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:42 volumio volumio[9806]: info: Clearing queue after UPNP request Apr 05 13:01:42 volumio volumio[9806]: info: CoreStateMachine::ClearQueue Apr 05 13:01:42 volumio volumio[9806]: info: CoreStateMachine::stop Apr 05 13:01:42 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 13:01:42 volumio volumio[9806]: UNSET VOLATILE Apr 05 13:01:42 volumio volumio[9806]: info: CorePlayQueue::clearPlayQueue Apr 05 13:01:42 volumio volumio[9806]: info: CorePlayQueue::saveQueue Apr 05 13:01:42 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:42 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:42 volumio volumio[9806]: info: CoreCommandRouter::volumioPushQueue Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: error: updateQueue error: null Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:42 volumio volumio[9806]: info: Starting UPNP Playback Apr 05 13:01:42 volumio volumio[9806]: info: Preparing playback through UPNP Apr 05 13:01:42 volumio volumio[9806]: info: CoreCommandRouter::volumioGetState Apr 05 13:01:42 volumio volumio[9806]: info: CorePlayQueue::getTrack 0 Apr 05 13:01:42 volumio volumio[9806]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 3ms Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 2ms Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 7ms Apr 05 13:01:42 volumio volumio[9806]: info: Apr 05 13:01:42 volumio volumio[9806]: ---------------------------- MPD announces system playlist update Apr 05 13:01:42 volumio volumio[9806]: info: Ignoring MPD Status Update Apr 05 13:01:42 volumio volumio[9806]: info: ------------------------------ 1ms Apr 05 13:01:43 volumio volumio[9806]: info: Apr 05 13:01:43 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:43 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:43 volumio volumio[9806]: info: Apr 05 13:01:43 volumio volumio[9806]: ---------------------------- MPD announces state update: player Apr 05 13:01:43 volumio volumio[9806]: info: ControllerMpd::getState Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand status Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::parseState Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:43 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:43 volumio volumio[9806]: In UPNP mode Apr 05 13:01:43 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":246,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"随想曲","artist":"童丽","album":"每一个晚上","uri":"http://183.240.185.13/amobile.music.tc.qq.com/F0000046SjIP3HiK5C.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=61330D62CED374AFD94AC0C6A6BB865591B47E3CAD5E3A09C4F670CBBAD1B385D1FBB279CBAD6F77CF5C58CB565F1270CBEFC7D67923C7D6&uin=1259438298&src=F000001hJYoW0PIJ3g.flac&redirect=1&fromtag=111114","trackType":""} Apr 05 13:01:43 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus stop Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:43 volumio volumio[9806]: info: ------------------------------ 416ms Apr 05 13:01:43 volumio volumio[9806]: verbose: ControllerMpd::parseTrackInfo Apr 05 13:01:43 volumio volumio[9806]: info: ControllerMpd::pushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::servicePushState Apr 05 13:01:43 volumio volumio[9806]: In UPNP mode Apr 05 13:01:43 volumio volumio[9806]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":246,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"随想曲","artist":"童丽","album":"每一个晚上","uri":"http://183.240.185.13/amobile.music.tc.qq.com/F0000046SjIP3HiK5C.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=61330D62CED374AFD94AC0C6A6BB865591B47E3CAD5E3A09C4F670CBBAD1B385D1FBB279CBAD6F77CF5C58CB565F1270CBEFC7D67923C7D6&uin=1259438298&src=F000001hJYoW0PIJ3g.flac&redirect=1&fromtag=111114","trackType":""} Apr 05 13:01:43 volumio volumio[9806]: verbose: CURRENT POSITION 0 Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::syncState stateService play Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::syncState currentStatus play Apr 05 13:01:43 volumio volumio[9806]: info: Received an update from plugin. extracting info from payload Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::pushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::volumioPushState Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 13:01:43 volumio volumio[9806]: info: ------------------------------ 23ms Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:43 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:43 volumio volumio[9806]: info: CoreCommandRouter::volumioGetQueue Apr 05 13:01:43 volumio volumio[9806]: info: CoreStateMachine::getQueue Apr 05 13:01:43 volumio volumio[9806]: info: CorePlayQueue::getQueue Apr 05 13:01:45 volumio volumio[9806]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 13:01:45 volumio volumio[9806]: { Error: connect ETIMEDOUT 162.125.2.6:443 Apr 05 13:01:45 volumio volumio[9806]: at Object._errnoException (util.js:1022:11) Apr 05 13:01:45 volumio volumio[9806]: at _exceptionWithHostPort (util.js:1044:20) Apr 05 13:01:45 volumio volumio[9806]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 05 13:01:45 volumio volumio[9806]: code: 'ETIMEDOUT', Apr 05 13:01:45 volumio volumio[9806]: errno: 'ETIMEDOUT', Apr 05 13:01:45 volumio volumio[9806]: syscall: 'connect', Apr 05 13:01:45 volumio volumio[9806]: address: '162.125.2.6', Apr 05 13:01:45 volumio volumio[9806]: port: 443 } Apr 05 13:01:45 volumio volumio[9806]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 13:01:46 volumio sudo[10646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-05 13:00 Apr 05 13:01:46 volumio sudo[10646]: 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 15:00:46 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="b3401e25b8d19b16b1038c2843d2deaf"