-- Logs begin at Thu 2020-09-24 20:42:43 UTC, end at Thu 2024-05-09 15:15:56 UTC. -- May 09 15:14:05 xxxxx volumio[29535]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:14:05 xxxxx volumio[29535]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:14:05 xxxxx volumio[29535]: Dload Upload Total Spent Left Speed May 09 15:14:05 xxxxx volumio[29535]: [235B blob data] May 09 15:14:05 xxxxx volumio[29535]: retrying in 5 seconds, trial 1 May 09 15:14:05 xxxxx volumio[29535]: Volumio Calling Home May 09 15:14:08 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 15:14:08 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:14:16 xxxxx volumio[29535]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:14:16 xxxxx volumio[29535]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:14:16 xxxxx volumio[29535]: Dload Upload Total Spent Left Speed May 09 15:14:16 xxxxx volumio[29535]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 09 15:14:16 xxxxx volumio[29535]: retrying in 5 seconds, trial 2 May 09 15:14:16 xxxxx volumio[29535]: Volumio Calling Home May 09 15:14:30 xxxxx volumio[29535]: info: May 09 15:14:30 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:14:30 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:14:30 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:14:30 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:14:30 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:14:30 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:14:30 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:14:30 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:14:30 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:14:30 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":72736,"duration":313,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Merry-Go-Round of Life (人生旋转木马)","artist":"久石让 (ひさいし じょう)","album":"Freedom Piano Stories 4","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000000BH1Ng2HFfvC.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=31BDB02B34651A1AE4BDBBB7B8124045C71FED2B1B569D154C877B69BB9824DD3F595B540EBD02A7B296A8AF325CE4B1D69725843C548E2A&uin=317524287&src=F000001MbJCw1H6g0A.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:14:30 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:14:30 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService pause May 09 15:14:30 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus stop May 09 15:14:30 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:14:30 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:14:30 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:14:30 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:14:30 xxxxx volumio[29535]: info: ------------------------------ 18ms May 09 15:15:11 xxxxx volumio[29535]: info: May 09 15:15:11 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:11 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:11 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:11 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:11 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:11 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:11 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:11 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:11 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:11 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73226,"duration":313,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Merry-Go-Round of Life (人生旋转木马)","artist":"久石让 (ひさいし じょう)","album":"Freedom Piano Stories 4","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000000BH1Ng2HFfvC.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=31BDB02B34651A1AE4BDBBB7B8124045C71FED2B1B569D154C877B69BB9824DD3F595B540EBD02A7B296A8AF325CE4B1D69725843C548E2A&uin=317524287&src=F000001MbJCw1H6g0A.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:15:11 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:11 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:11 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus stop May 09 15:15:11 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:11 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:11 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:11 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:11 xxxxx volumio[29535]: info: ------------------------------ 12ms May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:22 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:22 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:22 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:22 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:22 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:22 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:22 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:22 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:22 xxxxx volumio[29535]: 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":"Merry-Go-Round of Life (人生旋转木马)","artist":"久石让 (ひさいし じょう)","album":"Freedom Piano Stories 4","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000000BH1Ng2HFfvC.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=31BDB02B34651A1AE4BDBBB7B8124045C71FED2B1B569D154C877B69BB9824DD3F595B540EBD02A7B296A8AF325CE4B1D69725843C548E2A&uin=317524287&src=F000001MbJCw1H6g0A.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:15:22 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService stop May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::stPlaybackTimer May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 17ms May 09 15:15:22 xxxxx volumio[29535]: info: Clearing queue after UPNP request May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::ClearQueue May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::stop May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:15:22 xxxxx volumio[29535]: UNSET VOLATILE May 09 15:15:22 xxxxx volumio[29535]: info: CorePlayQueue::clearPlayQueue May 09 15:15:22 xxxxx volumio[29535]: info: CorePlayQueue::saveQueue May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushQueue May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: error: updateQueue error: null May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:22 xxxxx volumio[29535]: info: Starting UPNP Playback May 09 15:15:22 xxxxx volumio[29535]: info: Preparing playback through UPNP May 09 15:15:22 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetState May 09 15:15:22 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:22 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:22 xxxxx volumio[29535]: info: May 09 15:15:22 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:22 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:22 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:23 xxxxx volumio[29535]: info: May 09 15:15:23 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:23 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:23 xxxxx volumio[29535]: info: May 09 15:15:23 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:23 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:23 xxxxx volumio[29535]: info: May 09 15:15:23 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:23 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:23 xxxxx volumio[29535]: info: ------------------------------ 4ms May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:23 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:23 xxxxx volumio[29535]: In UPNP mode May 09 15:15:23 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bamboo","artist":"Raujika","album":"Art Of The World","uri":"http://118.123.167.189/amobile.music.tc.qq.com/M500000FD8u30GpZdf.mp3?guid=0000000052c67b170000017b9ba5b73a&vkey=56E77BF938AE89BB08BEA02BB8765697D356E18D885B4D31CC6A7846935141C2467B892502ED553A1752E7EFF7E1CC94ED2E18135FAEDF58&uin=317524287&redirect=1&fromtag=111042","trackType":""} May 09 15:15:23 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus stop May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:23 xxxxx volumio[29535]: info: ------------------------------ 16ms May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:23 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:23 xxxxx volumio[29535]: In UPNP mode May 09 15:15:23 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bamboo","artist":"Raujika","album":"Art Of The World","uri":"http://118.123.167.189/amobile.music.tc.qq.com/M500000FD8u30GpZdf.mp3?guid=0000000052c67b170000017b9ba5b73a&vkey=56E77BF938AE89BB08BEA02BB8765697D356E18D885B4D31CC6A7846935141C2467B892502ED553A1752E7EFF7E1CC94ED2E18135FAEDF58&uin=317524287&redirect=1&fromtag=111042","trackType":""} May 09 15:15:23 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:23 xxxxx volumio[29535]: info: Received an update from plugin. extracting info from payload May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:23 xxxxx volumio[29535]: info: ------------------------------ 25ms May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:23 xxxxx volumio[29535]: info: May 09 15:15:23 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:23 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:23 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:23 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:23 xxxxx volumio[29535]: 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":"Bamboo","artist":"Raujika","album":"Art Of The World","uri":"http://118.123.167.189/amobile.music.tc.qq.com/M500000FD8u30GpZdf.mp3?guid=0000000052c67b170000017b9ba5b73a&vkey=56E77BF938AE89BB08BEA02BB8765697D356E18D885B4D31CC6A7846935141C2467B892502ED553A1752E7EFF7E1CC94ED2E18135FAEDF58&uin=317524287&redirect=1&fromtag=111042","trackType":""} May 09 15:15:23 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService stop May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::stPlaybackTimer May 09 15:15:23 xxxxx volumio[29535]: info: ------------------------------ 13ms May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:23 xxxxx volumio[29535]: info: Clearing queue after UPNP request May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::ClearQueue May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::stop May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:15:23 xxxxx volumio[29535]: UNSET VOLATILE May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::clearPlayQueue May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::saveQueue May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushQueue May 09 15:15:23 xxxxx volumio[29535]: info: May 09 15:15:23 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:23 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:23 xxxxx volumio[29535]: error: updateQueue error: null May 09 15:15:23 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:23 xxxxx volumio[29535]: info: Starting UPNP Playback May 09 15:15:23 xxxxx volumio[29535]: info: Preparing playback through UPNP May 09 15:15:23 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetState May 09 15:15:23 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:23 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:24 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:24 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:24 xxxxx volumio[29535]: info: May 09 15:15:24 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:24 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 3ms May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:24 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:24 xxxxx volumio[29535]: In UPNP mode May 09 15:15:24 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":227,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1316 Kbps","isStreaming":false,"title":"Let You Down","artist":"Dawid Podsiadlo","album":"Let You Down","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000001xcdBS4PMIRP.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=F67B30C5699E49650E5B135EDF2009EEBB1E75AFE4D196AAA27BBB5C33926B000148D1B5C5B61B2F88FEDE5D4BA4EC3B5CFEA685190F1C47&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:24 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus stop May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 22ms May 09 15:15:24 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:24 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:24 xxxxx volumio[29535]: In UPNP mode May 09 15:15:24 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":227,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1316 Kbps","isStreaming":false,"title":"Let You Down","artist":"Dawid Podsiadlo","album":"Let You Down","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000001xcdBS4PMIRP.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=F67B30C5699E49650E5B135EDF2009EEBB1E75AFE4D196AAA27BBB5C33926B000148D1B5C5B61B2F88FEDE5D4BA4EC3B5CFEA685190F1C47&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:24 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:24 xxxxx volumio[29535]: info: Received an update from plugin. extracting info from payload May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:24 xxxxx volumio[29535]: info: ------------------------------ 35ms May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:24 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:24 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:24 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:24 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:24 xxxxx volumio[29535]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Raujika/Art%20Of%20The%20World/37dc327f-7b26-4dad-8a6e-f0bc34de3f92.jpg' May 09 15:15:26 xxxxx volumio[29535]: info: May 09 15:15:26 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:26 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:26 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:26 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:26 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:26 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:26 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:26 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:26 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:26 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:26 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:26 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:26 xxxxx volumio[29535]: 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 Down","artist":"Dawid Podsiadlo","album":"Let You Down","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000001xcdBS4PMIRP.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=F67B30C5699E49650E5B135EDF2009EEBB1E75AFE4D196AAA27BBB5C33926B000148D1B5C5B61B2F88FEDE5D4BA4EC3B5CFEA685190F1C47&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:26 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:26 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService stop May 09 15:15:26 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:26 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:26 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:26 xxxxx volumio[29535]: info: CoreStateMachine::stPlaybackTimer May 09 15:15:26 xxxxx volumio[29535]: info: ------------------------------ 17ms May 09 15:15:26 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:26 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:26 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:26 xxxxx volumio[29535]: info: Clearing queue after UPNP request May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::ClearQueue May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::stop May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:15:27 xxxxx volumio[29535]: UNSET VOLATILE May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::clearPlayQueue May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::saveQueue May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushQueue May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: error: updateQueue error: null May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:27 xxxxx volumio[29535]: info: Starting UPNP Playback May 09 15:15:27 xxxxx volumio[29535]: info: Preparing playback through UPNP May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetState May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:27 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:27 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:27 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:27 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:27 xxxxx volumio[29535]: In UPNP mode May 09 15:15:27 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"521 Kbps","isStreaming":false,"title":"Spanish Steps","artist":"Elizabeth Naccarato","album":"Souvenir D'italia","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F0000026cO500l6UsX.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=2268AA81314ADC0B92C9F36099564CD87FB67061B1498A65E976E926C48542E66F4FFBF787DAED8F3331644FD961A6C70058F6AA45A8109E&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:27 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus stop May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 16ms May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 12ms May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:27 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:27 xxxxx volumio[29535]: In UPNP mode May 09 15:15:27 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":301,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"Spanish Steps","artist":"Elizabeth Naccarato","album":"Souvenir D'italia","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F0000026cO500l6UsX.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=2268AA81314ADC0B92C9F36099564CD87FB67061B1498A65E976E926C48542E66F4FFBF787DAED8F3331644FD961A6C70058F6AA45A8109E&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:27 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:27 xxxxx volumio[29535]: info: Received an update from plugin. extracting info from payload May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 37ms May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:27 xxxxx volumio[29535]: info: May 09 15:15:27 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:27 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:27 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:27 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:27 xxxxx volumio[29535]: 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":"Spanish Steps","artist":"Elizabeth Naccarato","album":"Souvenir D'italia","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F0000026cO500l6UsX.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=2268AA81314ADC0B92C9F36099564CD87FB67061B1498A65E976E926C48542E66F4FFBF787DAED8F3331644FD961A6C70058F6AA45A8109E&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:27 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService stop May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::stPlaybackTimer May 09 15:15:27 xxxxx volumio[29535]: info: ------------------------------ 18ms May 09 15:15:27 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:27 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:27 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:28 xxxxx volumio[29535]: info: Clearing queue after UPNP request May 09 15:15:28 xxxxx volumio[29535]: info: CoreStateMachine::ClearQueue May 09 15:15:28 xxxxx volumio[29535]: info: CoreStateMachine::stop May 09 15:15:28 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:15:28 xxxxx volumio[29535]: UNSET VOLATILE May 09 15:15:28 xxxxx volumio[29535]: info: CorePlayQueue::clearPlayQueue May 09 15:15:28 xxxxx volumio[29535]: info: CorePlayQueue::saveQueue May 09 15:15:28 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:28 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:28 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushQueue May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: error: updateQueue error: null May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:28 xxxxx volumio[29535]: info: Starting UPNP Playback May 09 15:15:28 xxxxx volumio[29535]: info: Preparing playback through UPNP May 09 15:15:28 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetState May 09 15:15:28 xxxxx volumio[29535]: info: CorePlayQueue::getTrack 0 May 09 15:15:28 xxxxx volumio[29535]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 1ms May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: info: May 09 15:15:28 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:28 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:28 xxxxx volumio[29535]: info: ------------------------------ 2ms May 09 15:15:29 xxxxx volumio[29535]: info: May 09 15:15:29 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:29 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:29 xxxxx volumio[29535]: info: May 09 15:15:29 xxxxx volumio[29535]: ---------------------------- MPD announces system playlist update May 09 15:15:29 xxxxx volumio[29535]: info: Ignoring MPD Status Update May 09 15:15:29 xxxxx volumio[29535]: info: May 09 15:15:29 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:29 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:29 xxxxx volumio[29535]: info: ------------------------------ 6ms May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:29 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:29 xxxxx volumio[29535]: In UPNP mode May 09 15:15:29 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"335 Kbps","isStreaming":false,"title":"春天花会开","artist":"任贤齐","album":"流浪汉","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000001uLmN82SO9ay.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=81835E1F04208A57315E53F756C56C31D3A48A7D7D7FF96587F8589F2AF9B981AB788103F7D89A5C2CEFAF04A08DB656A16CD87A86C23236&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:29 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus stop May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:29 xxxxx volumio[29535]: info: ------------------------------ 18ms May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:29 xxxxx volumio[29535]: info: ControllerMpd::pushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::servicePushState May 09 15:15:29 xxxxx volumio[29535]: In UPNP mode May 09 15:15:29 xxxxx volumio[29535]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"515 Kbps","isStreaming":false,"title":"春天花会开","artist":"任贤齐","album":"流浪汉","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000001uLmN82SO9ay.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=81835E1F04208A57315E53F756C56C31D3A48A7D7D7FF96587F8589F2AF9B981AB788103F7D89A5C2CEFAF04A08DB656A16CD87A86C23236&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:15:29 xxxxx volumio[29535]: verbose: CURRENT POSITION 0 May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::syncState stateService play May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::syncState currentStatus play May 09 15:15:29 xxxxx volumio[29535]: info: Received an update from plugin. extracting info from payload May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::pushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::volumioPushState May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:15:29 xxxxx volumio[29535]: info: ------------------------------ 46ms May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:29 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:29 xxxxx volumio[29535]: info: CoreCommandRouter::volumioGetQueue May 09 15:15:29 xxxxx volumio[29535]: info: CoreStateMachine::getQueue May 09 15:15:29 xxxxx volumio[29535]: info: CorePlayQueue::getQueue May 09 15:15:29 xxxxx volumio[29535]: info: May 09 15:15:29 xxxxx volumio[29535]: ---------------------------- MPD announces state update: player May 09 15:15:29 xxxxx volumio[29535]: info: ControllerMpd::getState May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand status May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::parseState May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:15:29 xxxxx volumio[29535]: verbose: ControllerMpd::parseTrackInfo May 09 15:15:56 xxxxx systemd-journal[220]: Suppressed 15 messages from /system.slice/volumio.service May 09 15:15:56 xxxxx volumio[29535]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 15:15:56 xxxxx volumio[29535]: { Error: connect ETIMEDOUT 69.63.186.30:443 May 09 15:15:56 xxxxx volumio[29535]: at Object._errnoException (util.js:1022:11) May 09 15:15:56 xxxxx volumio[29535]: at _exceptionWithHostPort (util.js:1044:20) May 09 15:15:56 xxxxx volumio[29535]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 09 15:15:56 xxxxx volumio[29535]: code: 'ETIMEDOUT', May 09 15:15:56 xxxxx volumio[29535]: errno: 'ETIMEDOUT', May 09 15:15:56 xxxxx volumio[29535]: syscall: 'connect', May 09 15:15:56 xxxxx volumio[29535]: address: '69.63.186.30', May 09 15:15:56 xxxxx volumio[29535]: port: 443 } May 09 15:15:56 xxxxx volumio[29535]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 15:15:56 xxxxx sudo[30217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 15:14 May 09 15:15:56 xxxxx sudo[30217]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"