-- Logs begin at Sun 2024-10-06 06:49:30 UTC, end at Sun 2024-10-06 07:01:34 UTC. -- Oct 06 07:00:01 volumio volumio[1072]: info: Oct 06 07:00:01 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:01 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:01 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:01 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:01 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:01 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:01 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:01 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:00:01 volumio volumio[1072]: 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":"El Ultimo Verano","artist":"Julio Iglesias","album":"La Carretera","uri":"http://aqqmusic.tc.qq.com/M800002u4IvU3vRp3L.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=C2F683AEAE9896865BCCD3DE90A18840679ED9741C22C37FF7D15DC6007277E4DBA9E95E02D55DD06FC7C812AD4736F81CAAA8237F31C261&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:01 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer Oct 06 07:00:01 volumio volumio[1072]: info: ------------------------------ 22ms Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:01 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:01 volumio volumio[1072]: info: Clearing queue after UPNP request Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::ClearQueue Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::stop Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 07:00:01 volumio volumio[1072]: UNSET VOLATILE Oct 06 07:00:01 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue Oct 06 07:00:01 volumio volumio[1072]: info: CorePlayQueue::saveQueue Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue Oct 06 07:00:01 volumio volumio[1072]: info: Oct 06 07:00:01 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:01 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:01 volumio volumio[1072]: error: updateQueue error: null Oct 06 07:00:01 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:01 volumio volumio[1072]: info: Starting UPNP Playback Oct 06 07:00:01 volumio volumio[1072]: info: Preparing playback through UPNP Oct 06 07:00:01 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 06 07:00:01 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:00:01 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 07:00:02 volumio volumio[1072]: info: Oct 06 07:00:02 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:02 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:02 volumio volumio[1072]: info: Oct 06 07:00:02 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:02 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:02 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:02 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:02 volumio volumio[1072]: info: Oct 06 07:00:02 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:02 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:02 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:02 volumio volumio[1072]: info: Oct 06 07:00:02 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:02 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:02 volumio volumio[1072]: info: ------------------------------ 7ms Oct 06 07:00:02 volumio volumio[1072]: info: Oct 06 07:00:02 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:02 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:02 volumio volumio[1072]: info: ------------------------------ 0ms Oct 06 07:00:02 volumio volumio[1072]: info: Oct 06 07:00:02 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:02 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:02 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:03 volumio volumio[1072]: info: Oct 06 07:00:03 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:03 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:03 volumio volumio[1072]: info: Oct 06 07:00:03 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:03 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:03 volumio volumio[1072]: info: Oct 06 07:00:03 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:03 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:03 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:03 volumio volumio[1072]: In UPNP mode Oct 06 07:00:03 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Agua Dulce, Agua Salá","artist":"Julio Iglesias","album":"La Carretera","uri":"http://aqqmusic.tc.qq.com/M800001vj1uC37rfj4.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=16A6861C03392114DDBBBF01D9DF4987DD32A511A2FFCCDE5FECD8586D8A8F325EBE679764CAD4EC7A58E126C90A920D73BC13D4FF535CE1&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:03 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:03 volumio volumio[1072]: info: ------------------------------ 23ms Oct 06 07:00:03 volumio volumio[1072]: info: ------------------------------ 22ms Oct 06 07:00:03 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:03 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:03 volumio volumio[1072]: In UPNP mode Oct 06 07:00:03 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Agua Dulce, Agua Salá","artist":"Julio Iglesias","album":"La Carretera","uri":"http://aqqmusic.tc.qq.com/M800001vj1uC37rfj4.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=16A6861C03392114DDBBBF01D9DF4987DD32A511A2FFCCDE5FECD8586D8A8F325EBE679764CAD4EC7A58E126C90A920D73BC13D4FF535CE1&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:03 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:00:03 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:03 volumio volumio[1072]: info: ------------------------------ 36ms Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:03 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:03 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:03 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:03 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:13 volumio volumio[1072]: info: Oct 06 07:00:13 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:13 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:13 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:13 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:13 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:13 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:13 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:13 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:00:13 volumio volumio[1072]: 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":"Agua Dulce, Agua Salá","artist":"Julio Iglesias","album":"La Carretera","uri":"http://aqqmusic.tc.qq.com/M800001vj1uC37rfj4.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=16A6861C03392114DDBBBF01D9DF4987DD32A511A2FFCCDE5FECD8586D8A8F325EBE679764CAD4EC7A58E126C90A920D73BC13D4FF535CE1&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:13 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer Oct 06 07:00:13 volumio volumio[1072]: info: ------------------------------ 17ms Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:13 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:13 volumio volumio[1072]: info: Clearing queue after UPNP request Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::ClearQueue Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::stop Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 07:00:13 volumio volumio[1072]: UNSET VOLATILE Oct 06 07:00:13 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue Oct 06 07:00:13 volumio volumio[1072]: info: CorePlayQueue::saveQueue Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue Oct 06 07:00:13 volumio volumio[1072]: info: Oct 06 07:00:13 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:13 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:13 volumio volumio[1072]: error: updateQueue error: null Oct 06 07:00:13 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:13 volumio volumio[1072]: info: Starting UPNP Playback Oct 06 07:00:13 volumio volumio[1072]: info: Preparing playback through UPNP Oct 06 07:00:13 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 06 07:00:13 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:00:13 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 07:00:14 volumio volumio[1072]: info: Oct 06 07:00:14 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:14 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:14 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:14 volumio volumio[1072]: info: Oct 06 07:00:14 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:14 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:14 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:14 volumio volumio[1072]: info: Oct 06 07:00:14 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:14 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:14 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:14 volumio volumio[1072]: info: Oct 06 07:00:14 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:14 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:14 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:14 volumio volumio[1072]: info: Oct 06 07:00:14 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:14 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:14 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:14 volumio volumio[1072]: info: Oct 06 07:00:14 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:14 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:14 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:14 volumio dhcpcd[1009]: eth0: adding route to 192.168.3.0/24 Oct 06 07:00:14 volumio dhcpcd[1009]: eth0: adding default route via 192.168.3.101 Oct 06 07:00:14 volumio dhcpcd[1009]: eth0: ipv4_addroute: File exists Oct 06 07:00:14 volumio dhcpcd[1009]: eth0: removing route to 192.168.3.0/24 Oct 06 07:00:20 volumio volumio[1072]: info: Oct 06 07:00:20 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:20 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:20 volumio volumio[1072]: info: Oct 06 07:00:20 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:20 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:20 volumio volumio[1072]: info: Oct 06 07:00:20 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:20 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:20 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:20 volumio volumio[1072]: In UPNP mode Oct 06 07:00:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Romeo And Juliet (Love Theme)","artist":"David Osborne","album":"Keys To The Heart: Romantic Solo Piano","uri":"http://aqqmusic.tc.qq.com/M800002dyYHa0AqkyU.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=9A57C4E0E402425821AD82C3E09A88D4D334855C49D8B6EED3547AC7F516980BB12149B871467B43A1AEB45D5515532A058F8627B16CA523&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:20 volumio volumio[1072]: info: ------------------------------ 27ms Oct 06 07:00:20 volumio volumio[1072]: info: ------------------------------ 23ms Oct 06 07:00:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:20 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:20 volumio volumio[1072]: In UPNP mode Oct 06 07:00:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Romeo And Juliet (Love Theme)","artist":"David Osborne","album":"Keys To The Heart: Romantic Solo Piano","uri":"http://aqqmusic.tc.qq.com/M800002dyYHa0AqkyU.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=9A57C4E0E402425821AD82C3E09A88D4D334855C49D8B6EED3547AC7F516980BB12149B871467B43A1AEB45D5515532A058F8627B16CA523&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:00:20 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:20 volumio volumio[1072]: info: ------------------------------ 34ms Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:20 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:20 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:20 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:20 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:27 volumio volumio[1072]: info: Oct 06 07:00:27 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:27 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:27 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:27 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:27 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:27 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:27 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:27 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:27 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:27 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:27 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:27 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:00:27 volumio volumio[1072]: 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":"Romeo And Juliet (Love Theme)","artist":"David Osborne","album":"Keys To The Heart: Romantic Solo Piano","uri":"http://aqqmusic.tc.qq.com/M800002dyYHa0AqkyU.mp3?guid=0000000004cb49d2000001871e73f5cf&vkey=9A57C4E0E402425821AD82C3E09A88D4D334855C49D8B6EED3547AC7F516980BB12149B871467B43A1AEB45D5515532A058F8627B16CA523&uin=1259438298&redirect=1&fromtag=111093","trackType":""} Oct 06 07:00:27 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:27 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop Oct 06 07:00:27 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:00:27 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:27 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:27 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer Oct 06 07:00:27 volumio volumio[1072]: info: ------------------------------ 25ms Oct 06 07:00:27 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:27 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:27 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:28 volumio volumio[1072]: info: Clearing queue after UPNP request Oct 06 07:00:28 volumio volumio[1072]: info: CoreStateMachine::ClearQueue Oct 06 07:00:28 volumio volumio[1072]: info: CoreStateMachine::stop Oct 06 07:00:28 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 07:00:28 volumio volumio[1072]: UNSET VOLATILE Oct 06 07:00:28 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue Oct 06 07:00:28 volumio volumio[1072]: info: CorePlayQueue::saveQueue Oct 06 07:00:28 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:28 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:28 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue Oct 06 07:00:28 volumio volumio[1072]: info: Oct 06 07:00:28 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:28 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:28 volumio volumio[1072]: error: updateQueue error: null Oct 06 07:00:28 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:28 volumio volumio[1072]: info: Starting UPNP Playback Oct 06 07:00:28 volumio volumio[1072]: info: Preparing playback through UPNP Oct 06 07:00:28 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 06 07:00:28 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:00:28 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 07:00:28 volumio volumio[1072]: info: Oct 06 07:00:28 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:28 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:28 volumio volumio[1072]: info: ------------------------------ 3ms Oct 06 07:00:28 volumio volumio[1072]: info: Oct 06 07:00:28 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:28 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:28 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:28 volumio volumio[1072]: info: Oct 06 07:00:28 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:29 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:29 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:29 volumio volumio[1072]: info: Oct 06 07:00:29 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:29 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:29 volumio volumio[1072]: info: ------------------------------ 0ms Oct 06 07:00:29 volumio volumio[1072]: info: Oct 06 07:00:29 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:29 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:29 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:29 volumio volumio[1072]: info: Oct 06 07:00:29 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:29 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:29 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:00:37 volumio volumio[1072]: info: Oct 06 07:00:37 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:37 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:37 volumio volumio[1072]: info: Oct 06 07:00:37 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:00:37 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:37 volumio volumio[1072]: info: Oct 06 07:00:37 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:00:37 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:00:37 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:37 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:37 volumio volumio[1072]: In UPNP mode Oct 06 07:00:37 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"199 Kbps","isStreaming":false,"title":"Romeo And Juliet (Love Theme)","artist":"David Osborne","album":"Keys To The Heart: Romantic Solo Piano","uri":"http://aqqmusic.tc.qq.com/F000002dyYHa0AqkyU.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=B0506046F1D7E7B309B90857F04C46410F35C1B2FBCC62815D121EDCE0EFB67FD8DA4205FA3568AC8ECC18215AA560DD71DDBAB14C306977&uin=1259438298&redirect=1&fromtag=111114","trackType":""} Oct 06 07:00:37 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:37 volumio volumio[1072]: info: ------------------------------ 13ms Oct 06 07:00:37 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:00:37 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:00:37 volumio volumio[1072]: In UPNP mode Oct 06 07:00:37 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"581 Kbps","isStreaming":false,"title":"Romeo And Juliet (Love Theme)","artist":"David Osborne","album":"Keys To The Heart: Romantic Solo Piano","uri":"http://aqqmusic.tc.qq.com/F000002dyYHa0AqkyU.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=B0506046F1D7E7B309B90857F04C46410F35C1B2FBCC62815D121EDCE0EFB67FD8DA4205FA3568AC8ECC18215AA560DD71DDBAB14C306977&uin=1259438298&redirect=1&fromtag=111114","trackType":""} Oct 06 07:00:37 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:00:37 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:00:37 volumio volumio[1072]: info: ------------------------------ 37ms Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:37 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:37 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:00:37 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:00:37 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:00:37 volumio volumio[1072]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/David%20Osborne/Keys%20To%20The%20Heart%3A%20Romantic%20Solo%20Piano/3a9df867-0110-47e3-a001-f1c6764a6c4e.jpg' Oct 06 07:00:44 volumio dhcpcd[1009]: eth0: adding route to 192.168.3.0/24 Oct 06 07:00:44 volumio dhcpcd[1009]: eth0: adding default route via 192.168.3.101 Oct 06 07:00:44 volumio dhcpcd[1009]: eth0: ipv4_addroute: File exists Oct 06 07:00:44 volumio dhcpcd[1009]: eth0: removing route to 192.168.3.0/24 Oct 06 07:01:08 volumio volumio[1072]: info: Oct 06 07:01:08 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:01:08 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:01:08 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:01:08 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:01:08 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:01:08 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:01:08 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:08 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:01:08 volumio volumio[1072]: 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":"Romeo And Juliet (Love Theme)","artist":"David Osborne","album":"Keys To The Heart: Romantic Solo Piano","uri":"http://aqqmusic.tc.qq.com/F000002dyYHa0AqkyU.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=B0506046F1D7E7B309B90857F04C46410F35C1B2FBCC62815D121EDCE0EFB67FD8DA4205FA3568AC8ECC18215AA560DD71DDBAB14C306977&uin=1259438298&redirect=1&fromtag=111114","trackType":""} Oct 06 07:01:08 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer Oct 06 07:01:08 volumio volumio[1072]: info: ------------------------------ 16ms Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:01:08 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:01:08 volumio volumio[1072]: info: Clearing queue after UPNP request Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::ClearQueue Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::stop Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 07:01:08 volumio volumio[1072]: UNSET VOLATILE Oct 06 07:01:08 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue Oct 06 07:01:08 volumio volumio[1072]: info: CorePlayQueue::saveQueue Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue Oct 06 07:01:08 volumio volumio[1072]: info: Oct 06 07:01:08 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:08 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:08 volumio volumio[1072]: error: updateQueue error: null Oct 06 07:01:08 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:08 volumio volumio[1072]: info: Starting UPNP Playback Oct 06 07:01:08 volumio volumio[1072]: info: Preparing playback through UPNP Oct 06 07:01:08 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 06 07:01:08 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:01:08 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 07:01:09 volumio volumio[1072]: info: Oct 06 07:01:09 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:09 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:09 volumio volumio[1072]: info: Oct 06 07:01:09 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:09 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:09 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:01:09 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:09 volumio volumio[1072]: info: Oct 06 07:01:09 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:09 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:09 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:09 volumio volumio[1072]: info: Oct 06 07:01:09 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:09 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:09 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:09 volumio volumio[1072]: info: Oct 06 07:01:09 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:09 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:09 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:01:09 volumio volumio[1072]: info: Oct 06 07:01:09 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:09 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:09 volumio volumio[1072]: info: ------------------------------ 2ms Oct 06 07:01:14 volumio dhcpcd[1009]: eth0: adding route to 192.168.3.0/24 Oct 06 07:01:14 volumio dhcpcd[1009]: eth0: adding default route via 192.168.3.101 Oct 06 07:01:14 volumio dhcpcd[1009]: eth0: ipv4_addroute: File exists Oct 06 07:01:14 volumio dhcpcd[1009]: eth0: removing route to 192.168.3.0/24 Oct 06 07:01:15 volumio volumio[1072]: info: Oct 06 07:01:15 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:01:15 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:01:15 volumio volumio[1072]: info: Oct 06 07:01:15 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:01:15 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:01:15 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:01:15 volumio volumio[1072]: In UPNP mode Oct 06 07:01:15 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let You Break My Heart Again","artist":"Laufey/Philharmonia Orchestra","album":"Let You Break My Heart Again","uri":"http://aqqmusic.tc.qq.com/F000001F7SSQ4IVlUg.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=B4F8489CF40651E728A6FEC67714F0D089204C74FB2FF090809291EDC29E678406CD89D446D6718785FC9A4A8CC7AE60138CC8DE39402BD7&uin=1259438298&src=F000004YwuqQ0r3jnR.flac&redirect=1&fromtag=111114","trackType":""} Oct 06 07:01:15 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:15 volumio volumio[1072]: info: ------------------------------ 38ms Oct 06 07:01:15 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:01:15 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:01:15 volumio volumio[1072]: In UPNP mode Oct 06 07:01:15 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":269,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let You Break My Heart Again","artist":"Laufey/Philharmonia Orchestra","album":"Let You Break My Heart Again","uri":"http://aqqmusic.tc.qq.com/F000001F7SSQ4IVlUg.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=B4F8489CF40651E728A6FEC67714F0D089204C74FB2FF090809291EDC29E678406CD89D446D6718785FC9A4A8CC7AE60138CC8DE39402BD7&uin=1259438298&src=F000004YwuqQ0r3jnR.flac&redirect=1&fromtag=111114","trackType":""} Oct 06 07:01:15 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:01:15 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:15 volumio volumio[1072]: info: ------------------------------ 30ms Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:01:15 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:01:15 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:01:15 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:01:15 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:01:30 volumio volumio[1072]: info: Oct 06 07:01:30 volumio volumio[1072]: ---------------------------- MPD announces state update: player Oct 06 07:01:30 volumio volumio[1072]: info: ControllerMpd::getState Oct 06 07:01:30 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Oct 06 07:01:30 volumio volumio[1072]: verbose: ControllerMpd::parseState Oct 06 07:01:30 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 07:01:30 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo Oct 06 07:01:30 volumio volumio[1072]: info: ControllerMpd::pushState Oct 06 07:01:30 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Oct 06 07:01:30 volumio volumio[1072]: info: CoreStateMachine::pushState Oct 06 07:01:30 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:30 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:30 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:01:30 volumio volumio[1072]: 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":"Let You Break My Heart Again","artist":"Laufey/Philharmonia Orchestra","album":"Let You Break My Heart Again","uri":"http://aqqmusic.tc.qq.com/F000001F7SSQ4IVlUg.flac?guid=0000000004cb49d2000001871e73f5cf&vkey=B4F8489CF40651E728A6FEC67714F0D089204C74FB2FF090809291EDC29E678406CD89D446D6718785FC9A4A8CC7AE60138CC8DE39402BD7&uin=1259438298&src=F000004YwuqQ0r3jnR.flac&redirect=1&fromtag=111114","trackType":""} Oct 06 07:01:30 volumio volumio[1072]: verbose: CURRENT POSITION 0 Oct 06 07:01:30 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop Oct 06 07:01:30 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play Oct 06 07:01:30 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:30 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:30 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer Oct 06 07:01:30 volumio volumio[1072]: info: ------------------------------ 14ms Oct 06 07:01:30 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Oct 06 07:01:30 volumio volumio[1072]: info: CoreStateMachine::getQueue Oct 06 07:01:30 volumio volumio[1072]: info: CorePlayQueue::getQueue Oct 06 07:01:31 volumio volumio[1072]: info: Clearing queue after UPNP request Oct 06 07:01:32 volumio volumio[1072]: info: CoreStateMachine::ClearQueue Oct 06 07:01:32 volumio volumio[1072]: info: CoreStateMachine::stop Oct 06 07:01:32 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 07:01:32 volumio volumio[1072]: UNSET VOLATILE Oct 06 07:01:32 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue Oct 06 07:01:32 volumio volumio[1072]: info: CorePlayQueue::saveQueue Oct 06 07:01:32 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Oct 06 07:01:32 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 07:01:32 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: error: updateQueue error: null Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 7ms Oct 06 07:01:32 volumio volumio[1072]: info: Starting UPNP Playback Oct 06 07:01:32 volumio volumio[1072]: info: Preparing playback through UPNP Oct 06 07:01:32 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Oct 06 07:01:32 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Oct 06 07:01:32 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 4ms Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:32 volumio volumio[1072]: info: Oct 06 07:01:32 volumio volumio[1072]: ---------------------------- MPD announces system playlist update Oct 06 07:01:32 volumio volumio[1072]: info: Ignoring MPD Status Update Oct 06 07:01:32 volumio volumio[1072]: info: ------------------------------ 1ms Oct 06 07:01:34 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 07:01:34 volumio volumio[1072]: { Error: connect ETIMEDOUT 74.86.226.234:443 Oct 06 07:01:34 volumio volumio[1072]: at Object._errnoException (util.js:1022:11) Oct 06 07:01:34 volumio volumio[1072]: at _exceptionWithHostPort (util.js:1044:20) Oct 06 07:01:34 volumio volumio[1072]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 06 07:01:34 volumio volumio[1072]: code: 'ETIMEDOUT', Oct 06 07:01:34 volumio volumio[1072]: errno: 'ETIMEDOUT', Oct 06 07:01:34 volumio volumio[1072]: syscall: 'connect', Oct 06 07:01:34 volumio volumio[1072]: address: '74.86.226.234', Oct 06 07:01:34 volumio volumio[1072]: port: 443 } Oct 06 07:01:34 volumio volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 07:01:34 volumio sudo[3775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 07:00 Oct 06 07:01:34 volumio sudo[3775]: 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"