-- Logs begin at Sat 2024-07-20 17:55:40 CST, end at Sat 2024-07-20 18:52:16 CST. -- Jul 20 18:51:09 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:51:09 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:51:09 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:51:09 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:51:09 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:51:09 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:51:09 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:09 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:51:09 volumio volumio[1030]: info: Jul 20 18:51:09 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:09 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:09 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:51:09 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:09 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:51:09 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:51:09 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:09 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:09 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:51:10 volumio volumio[1030]: info: Jul 20 18:51:10 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:10 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:10 volumio volumio[1030]: info: Jul 20 18:51:10 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:10 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:10 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:10 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:10 volumio volumio[1030]: info: Jul 20 18:51:10 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:10 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:10 volumio volumio[1030]: info: Jul 20 18:51:10 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:10 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:10 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:10 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:10 volumio volumio[1030]: info: Jul 20 18:51:10 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:10 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:10 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:10 volumio volumio[1030]: info: Jul 20 18:51:10 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:10 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:10 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:20 volumio sudo[5227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 20 18:51:20 volumio sudo[5228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 20 18:51:20 volumio sudo[5227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 18:51:20 volumio sudo[5228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 18:51:20 volumio sudo[5227]: pam_unix(sudo:session): session closed for user root Jul 20 18:51:20 volumio sudo[5228]: pam_unix(sudo:session): session closed for user root Jul 20 18:51:20 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.50.237 from 192.168.50.202 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/26.1 Chrome/122.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 20 18:51:20 volumio sudo[5246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 20 18:51:20 volumio sudo[5248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 20 18:51:20 volumio sudo[5246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 18:51:20 volumio sudo[5246]: pam_unix(sudo:session): session closed for user root Jul 20 18:51:20 volumio sudo[5248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 18:51:20 volumio sudo[5248]: pam_unix(sudo:session): session closed for user root Jul 20 18:51:20 volumio volumio[1030]: verbose: New Socket.io Connection to 192.168.50.237 from 192.168.50.202 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/26.1 Chrome/122.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 20 18:51:21 volumio volumio[1030]: info: Received Get System Info Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 18:51:21 volumio volumio[1030]: info: Discovery: Getting this device information Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:21 volumio volumio[1030]: info: Listing playlists Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 18:51:21 volumio volumio[1030]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E4%BA%91%E8%93%AC/%E7%93%A6%E5%B0%94%E7%99%BB%E6%B9%96/911d8430-0187-4908-bc3e-315c182e7663.jpg' Jul 20 18:51:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 20 18:51:22 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 20 18:51:22 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 20 18:51:22 volumio volumio[1030]: info: Received Get System Info Jul 20 18:51:22 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 18:51:22 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 18:51:22 volumio volumio[1030]: info: Discovery: Getting this device information Jul 20 18:51:22 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:22 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 18:51:23 volumio volumio[1030]: info: Jul 20 18:51:23 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:23 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:23 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:23 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 20 18:51:23 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Jul 20 18:51:23 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:23 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:23 volumio volumio[1030]: info: sendMpdCommand clearerror took 2 milliseconds Jul 20 18:51:23 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:51:23 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:23 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:23 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:23 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:23 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 18:51:23 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:23 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:23 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"白兰鸽巡游记","artist":"丢火车乐队","album":"游歌","uri":"http://isure6.stream.qqmusic.qq.com/M800002xlz1O23gxxx.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=2721110A16D26B0541BFCB01A39CBEF1910D8370E1DAE269F8676125A69F86DCA9584797F93DE9ADDA93E2A476BA403907CC2E4A9A456F01&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:23 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:23 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 20 18:51:23 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:51:23 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:23 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:23 volumio volumio[1030]: info: No code Jul 20 18:51:23 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:23 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:23 volumio volumio[1030]: info: ------------------------------ 23ms Jul 20 18:51:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 20 18:51:32 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:51:32 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:51:32 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:51:32 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:51:32 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:51:32 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:51:32 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:32 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:51:32 volumio volumio[1030]: info: Jul 20 18:51:32 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:32 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:32 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:51:32 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:32 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:51:32 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:51:32 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:32 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:32 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:33 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:33 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:33 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:33 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:33 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:33 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:33 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:33 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:33 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:33 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:33 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:33 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:33 volumio volumio[1030]: info: Jul 20 18:51:33 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:33 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:33 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:34 volumio volumio[1030]: info: Jul 20 18:51:34 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:34 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:34 volumio volumio[1030]: info: Jul 20 18:51:34 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:34 volumio volumio[1030]: info: sendMpdCommand status took 215 milliseconds Jul 20 18:51:34 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:34 volumio volumio[1030]: info: ------------------------------ 6ms Jul 20 18:51:34 volumio volumio[1030]: info: sendMpdCommand status took 2 milliseconds Jul 20 18:51:34 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:34 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:34 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:34 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":398,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我们飞向太空","artist":"刺猬乐队","album":"幻象波普星","uri":"http://isure6.stream.qqmusic.qq.com/M8000037sTow4TPzrV.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=6AF18D9102213A78EA0D1B0C9A3E0A61E8CB4ADDB942ACA4D33C59180C786DFA4C6140DD3F994A4995FF544CCBDE3707250E6521D5C9EACA&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:34 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:51:34 volumio volumio[1030]: info: ------------------------------ 221ms Jul 20 18:51:34 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 20 18:51:34 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:34 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:34 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:34 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":398,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我们飞向太空","artist":"刺猬乐队","album":"幻象波普星","uri":"http://isure6.stream.qqmusic.qq.com/M8000037sTow4TPzrV.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=6AF18D9102213A78EA0D1B0C9A3E0A61E8CB4ADDB942ACA4D33C59180C786DFA4C6140DD3F994A4995FF544CCBDE3707250E6521D5C9EACA&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:34 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:34 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:34 volumio volumio[1030]: info: ------------------------------ 23ms Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:34 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:34 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:34 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:34 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:40 volumio volumio[1030]: info: Jul 20 18:51:40 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:40 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:40 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:40 volumio volumio[1030]: info: sendMpdCommand status took 1 milliseconds Jul 20 18:51:40 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:40 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:40 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 20 18:51:40 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:40 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:40 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:40 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:40 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:40 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:40 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我们飞向太空","artist":"刺猬乐队","album":"幻象波普星","uri":"http://isure6.stream.qqmusic.qq.com/M8000037sTow4TPzrV.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=6AF18D9102213A78EA0D1B0C9A3E0A61E8CB4ADDB942ACA4D33C59180C786DFA4C6140DD3F994A4995FF544CCBDE3707250E6521D5C9EACA&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:40 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:40 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 20 18:51:40 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:40 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:40 volumio volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 20 18:51:40 volumio volumio[1030]: info: ------------------------------ 15ms Jul 20 18:51:40 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:40 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:40 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:41 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:51:41 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:51:41 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:51:41 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:51:41 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:51:41 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:51:41 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:41 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:41 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:51:41 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:51:41 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:41 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:41 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 3ms Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:41 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:41 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:41 volumio volumio[1030]: info: Jul 20 18:51:41 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:41 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:41 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:42 volumio volumio[1030]: info: Jul 20 18:51:42 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:42 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:42 volumio volumio[1030]: info: Jul 20 18:51:42 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:42 volumio volumio[1030]: info: sendMpdCommand status took 220 milliseconds Jul 20 18:51:42 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:42 volumio volumio[1030]: info: ------------------------------ 5ms Jul 20 18:51:42 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Jul 20 18:51:42 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:42 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:42 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:42 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"不会说话的爱情","artist":"周云蓬","album":"牛羊下山","uri":"http://isure6.stream.qqmusic.qq.com/M800000Kof394UXdut.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=0F6A32AFB4C16DC5E312ADB9EC82A449C381FC6578A887191D15D684641A3DA1A27F4805FDE6017A741720711570B806BE2E5DBBEA6D4F36&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:42 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:51:42 volumio volumio[1030]: info: ------------------------------ 227ms Jul 20 18:51:42 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 20 18:51:42 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:42 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:42 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:42 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"不会说话的爱情","artist":"周云蓬","album":"牛羊下山","uri":"http://isure6.stream.qqmusic.qq.com/M800000Kof394UXdut.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=0F6A32AFB4C16DC5E312ADB9EC82A449C381FC6578A887191D15D684641A3DA1A27F4805FDE6017A741720711570B806BE2E5DBBEA6D4F36&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:42 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:42 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:42 volumio volumio[1030]: info: ------------------------------ 27ms Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:42 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:42 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:42 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:42 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:51 volumio volumio[1030]: info: Jul 20 18:51:51 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:51 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:51 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:51 volumio volumio[1030]: info: sendMpdCommand status took 1 milliseconds Jul 20 18:51:51 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:51 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:51 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:51:51 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:51 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:51 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:51 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"不会说话的爱情","artist":"周云蓬","album":"牛羊下山","uri":"http://isure6.stream.qqmusic.qq.com/M800000Kof394UXdut.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=0F6A32AFB4C16DC5E312ADB9EC82A449C381FC6578A887191D15D684641A3DA1A27F4805FDE6017A741720711570B806BE2E5DBBEA6D4F36&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:51 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 20 18:51:51 volumio volumio[1030]: info: ------------------------------ 12ms Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:51 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:51 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:51:51 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:51:51 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:51:51 volumio volumio[1030]: info: Jul 20 18:51:51 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:51 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:51 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:51:51 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:51 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:51:51 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:51:51 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:51 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:51 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:52 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:52 volumio volumio[1030]: info: sendMpdCommand status took 219 milliseconds Jul 20 18:51:52 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 3ms Jul 20 18:51:52 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Jul 20 18:51:52 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:52 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:52 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:52 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"不会说话的爱情","artist":"周云蓬","album":"牛羊下山","uri":"http://isure6.stream.qqmusic.qq.com/M800000Kof394UXdut.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=0F6A32AFB4C16DC5E312ADB9EC82A449C381FC6578A887191D15D684641A3DA1A27F4805FDE6017A741720711570B806BE2E5DBBEA6D4F36&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:52 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 226ms Jul 20 18:51:52 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:52 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:52 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:52 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"不会说话的爱情","artist":"周云蓬","album":"牛羊下山","uri":"http://isure6.stream.qqmusic.qq.com/M800000Kof394UXdut.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=0F6A32AFB4C16DC5E312ADB9EC82A449C381FC6578A887191D15D684641A3DA1A27F4805FDE6017A741720711570B806BE2E5DBBEA6D4F36&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:52 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:52 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 25ms Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:52 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:52 volumio volumio[1030]: info: sendMpdCommand status took 1 milliseconds Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:52 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:51:52 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:52 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:52 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"不会说话的爱情","artist":"周云蓬","album":"牛羊下山","uri":"http://isure6.stream.qqmusic.qq.com/M800000Kof394UXdut.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=0F6A32AFB4C16DC5E312ADB9EC82A449C381FC6578A887191D15D684641A3DA1A27F4805FDE6017A741720711570B806BE2E5DBBEA6D4F36&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:52 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 26ms Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:52 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:51:52 volumio volumio[1030]: info: Jul 20 18:51:52 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:52 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:52 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:51:52 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:52 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:51:52 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:51:52 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:52 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:52 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:53 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:53 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:53 volumio volumio[1030]: info: Jul 20 18:51:53 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:53 volumio volumio[1030]: info: sendMpdCommand status took 213 milliseconds Jul 20 18:51:53 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:53 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:53 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 20 18:51:53 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:53 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:53 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:53 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:53 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":364,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:51:53 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:51:53 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:53 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:53 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":364,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:51:53 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:53 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 226ms Jul 20 18:51:53 volumio volumio[1030]: info: ------------------------------ 20ms Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:53 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:53 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:53 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:53 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:56 volumio volumio[1030]: info: Jul 20 18:51:56 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:56 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:56 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:56 volumio volumio[1030]: info: sendMpdCommand status took 1 milliseconds Jul 20 18:51:56 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:56 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:56 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:51:56 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:56 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:56 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:56 volumio volumio[1030]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:51:56 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 20 18:51:56 volumio volumio[1030]: info: ------------------------------ 16ms Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:56 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:56 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:51:56 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:51:56 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:51:56 volumio volumio[1030]: info: Jul 20 18:51:56 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:56 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:56 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:51:56 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:56 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:51:56 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:51:56 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:51:56 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:51:56 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 3ms Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:57 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:51:57 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:51:57 volumio volumio[1030]: info: Jul 20 18:51:57 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:51:57 volumio volumio[1030]: info: sendMpdCommand status took 219 milliseconds Jul 20 18:51:57 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 4ms Jul 20 18:51:57 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:51:57 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 20 18:51:57 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:57 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:51:57 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:57 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:57 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"恋曲1980","artist":"好妹妹","album":"我们民谣2022 第9期","uri":"http://isure6.stream.qqmusic.qq.com/M800003g7NCq2dmVu8.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=53358F251110F4295A7CBDBE8937727452929AB8D53061D831120C7EB7562F3FB4402DD272BE831E35E204E7E5D104D3DE023D1849DFE5C8&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:57 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:51:57 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:51:57 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:51:57 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"恋曲1980","artist":"好妹妹","album":"我们民谣2022 第9期","uri":"http://isure6.stream.qqmusic.qq.com/M800003g7NCq2dmVu8.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=53358F251110F4295A7CBDBE8937727452929AB8D53061D831120C7EB7562F3FB4402DD272BE831E35E204E7E5D104D3DE023D1849DFE5C8&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:51:57 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:51:57 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 234ms Jul 20 18:51:57 volumio volumio[1030]: info: ------------------------------ 24ms Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:57 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:51:57 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:51:57 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:51:57 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:00 volumio volumio[1030]: info: Jul 20 18:52:00 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:52:00 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:52:00 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:52:00 volumio volumio[1030]: info: sendMpdCommand status took 2 milliseconds Jul 20 18:52:00 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:52:00 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:52:00 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:52:00 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:52:00 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:52:00 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:52:00 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:00 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:00 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:52:00 volumio volumio[1030]: 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":"恋曲1980","artist":"好妹妹","album":"我们民谣2022 第9期","uri":"http://isure6.stream.qqmusic.qq.com/M800003g7NCq2dmVu8.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=53358F251110F4295A7CBDBE8937727452929AB8D53061D831120C7EB7562F3FB4402DD272BE831E35E204E7E5D104D3DE023D1849DFE5C8&uin=1152921504815414004&redirect=1&fromtag=111093","trackType":""} Jul 20 18:52:00 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:52:00 volumio volumio[1030]: info: CoreStateMachine::syncState stateService stop Jul 20 18:52:00 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:52:00 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:00 volumio volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 20 18:52:00 volumio volumio[1030]: info: ------------------------------ 12ms Jul 20 18:52:00 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:00 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:00 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:00 volumio volumio[1030]: info: Clearing queue after UPNP request Jul 20 18:52:01 volumio volumio[1030]: info: CoreStateMachine::ClearQueue Jul 20 18:52:01 volumio volumio[1030]: info: CoreStateMachine::stop Jul 20 18:52:01 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 18:52:01 volumio volumio[1030]: info: CorePlayQueue::clearPlayQueue Jul 20 18:52:01 volumio volumio[1030]: info: CorePlayQueue::saveQueue Jul 20 18:52:01 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:01 volumio volumio[1030]: info: CoreCommandRouter::volumioPushQueue Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: error: updateQueue error: null Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:52:01 volumio volumio[1030]: info: Starting UPNP Playback Jul 20 18:52:01 volumio volumio[1030]: info: Preparing playback through UPNP Jul 20 18:52:01 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 20 18:52:01 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 20 18:52:01 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 2ms Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 0ms Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:01 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:01 volumio volumio[1030]: info: ------------------------------ 1ms Jul 20 18:52:01 volumio volumio[1030]: info: Jul 20 18:52:01 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:52:01 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:52:01 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:52:02 volumio volumio[1030]: info: sendMpdCommand status took 203 milliseconds Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:52:02 volumio volumio[1030]: info: Jul 20 18:52:02 volumio volumio[1030]: ---------------------------- MPD announces system playlist update Jul 20 18:52:02 volumio volumio[1030]: info: Ignoring MPD Status Update Jul 20 18:52:02 volumio volumio[1030]: info: Jul 20 18:52:02 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:52:02 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:52:02 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:52:02 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:52:02 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:52:02 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":364,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:52:02 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Jul 20 18:52:02 volumio volumio[1030]: info: ------------------------------ 212ms Jul 20 18:52:02 volumio volumio[1030]: info: ------------------------------ 6ms Jul 20 18:52:02 volumio volumio[1030]: info: sendMpdCommand status took 10 milliseconds Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:52:02 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:52:02 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:52:02 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:52:02 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:52:02 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":364,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:52:02 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:52:02 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:02 volumio volumio[1030]: info: ------------------------------ 31ms Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:02 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:02 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:02 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:02 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:10 volumio volumio[1030]: info: Jul 20 18:52:10 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:52:10 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:52:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:52:10 volumio volumio[1030]: info: sendMpdCommand status took 2 milliseconds Jul 20 18:52:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:52:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:52:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:52:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:52:10 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:52:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:52:10 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:52:10 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110000,"duration":364,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:52:10 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:52:10 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:52:10 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:52:10 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:52:10 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:52:10 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:10 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:10 volumio volumio[1030]: info: ------------------------------ 13ms Jul 20 18:52:10 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:10 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:10 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:10 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:10 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:10 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:12 volumio volumio[1030]: info: Jul 20 18:52:12 volumio volumio[1030]: ---------------------------- MPD announces state update: player Jul 20 18:52:12 volumio volumio[1030]: info: ControllerMpd::getState Jul 20 18:52:12 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 20 18:52:12 volumio volumio[1030]: info: sendMpdCommand status took 1 milliseconds Jul 20 18:52:12 volumio volumio[1030]: verbose: ControllerMpd::parseState Jul 20 18:52:12 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 18:52:12 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 18:52:12 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 20 18:52:12 volumio volumio[1030]: info: ControllerMpd::pushState Jul 20 18:52:12 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Jul 20 18:52:12 volumio volumio[1030]: verbose: In UPNP mode Jul 20 18:52:12 volumio volumio[1030]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35000,"duration":364,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"九月","artist":"周云蓬","album":"清炒苦瓜","uri":"http://isure6.stream.qqmusic.qq.com/M5000000WTTA4DN1ey.mp3?guid=ffffffff8d708a350000018d3ab3dfd7&vkey=75EE1FEA79A1D5ED2DE48475B7DADBED71C6F3D83888B5D285EFBF7960428C0EFB65C072838DD297E7089128C4BAFBAE18BDA66694D5C49A&uin=1152921504815414004&redirect=1&fromtag=111042","trackType":""} Jul 20 18:52:12 volumio volumio[1030]: verbose: CURRENT POSITION 0 Jul 20 18:52:12 volumio volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 20 18:52:12 volumio volumio[1030]: info: CoreStateMachine::syncState currentStatus play Jul 20 18:52:12 volumio volumio[1030]: info: Received an update from plugin. extracting info from payload Jul 20 18:52:12 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 20 18:52:12 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:12 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:12 volumio volumio[1030]: info: CoreStateMachine::pushState Jul 20 18:52:12 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 20 18:52:12 volumio volumio[1030]: info: ------------------------------ 15ms Jul 20 18:52:12 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:12 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:12 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:12 volumio volumio[1030]: info: CoreCommandRouter::volumioGetQueue Jul 20 18:52:12 volumio volumio[1030]: info: CoreStateMachine::getQueue Jul 20 18:52:12 volumio volumio[1030]: info: CorePlayQueue::getQueue Jul 20 18:52:16 volumio volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 18:52:16 volumio volumio[1030]: Error: connect ETIMEDOUT 173.252.248.244:443 Jul 20 18:52:16 volumio volumio[1030]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 20 18:52:16 volumio volumio[1030]: errno: -110, Jul 20 18:52:16 volumio volumio[1030]: code: 'ETIMEDOUT', Jul 20 18:52:16 volumio volumio[1030]: syscall: 'connect', Jul 20 18:52:16 volumio volumio[1030]: address: '173.252.248.244', Jul 20 18:52:16 volumio volumio[1030]: port: 443 Jul 20 18:52:16 volumio volumio[1030]: } Jul 20 18:52:16 volumio volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 18:52:16 volumio sudo[5396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-20 18:51 Jul 20 18:52:16 volumio sudo[5396]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:59:47 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="5afa2cba9d125c5d0103869143516973"