-- Logs begin at Thu 2024-03-21 23:28:12 CST, end at Sat 2024-03-30 10:50:13 CST. -- Mar 30 10:49:28 VTS volumio[23312]: info: Mar 30 10:49:28 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:49:28 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:49:28 VTS volumio[23312]: info: Mar 30 10:49:28 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:49:28 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:49:28 VTS volumio[23312]: info: Mar 30 10:49:28 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:49:28 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:49:28 VTS volumio[23312]: info: sendMpdCommand status took 3 milliseconds Mar 30 10:49:28 VTS volumio[23312]: info: sendMpdCommand status took 2 milliseconds Mar 30 10:49:28 VTS volumio[23312]: info: sendMpdCommand status took 2 milliseconds Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:49:28 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 30 10:49:28 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 30 10:49:28 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:49:28 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:49:28 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:49:28 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:49:28 VTS volumio[23312]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85227,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"Find Your Way","artist":"In Color","album":"In Color (the Lamp Album)","uri":"http://58.222.38.160/amobile.music.tc.qq.com/F000001kl8h32gv5HN.flac?guid=930B11B52D8045C09D9BCF572C729CF0&vkey=E3419FA265BD365086697859CB3FE52C86B1EB43319B0502111C86CF7EB61559ECEE1806A72B08E334D986D5401CE874D549EFD3BCAE5ED9&uin=1152921504616111776&redirect=1&fromtag=101114","trackType":""} Mar 30 10:49:28 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::syncState stateService pause Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:49:28 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:49:28 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:49:28 VTS volumio[23312]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85227,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"Find Your Way","artist":"In Color","album":"In Color (the Lamp Album)","uri":"http://58.222.38.160/amobile.music.tc.qq.com/F000001kl8h32gv5HN.flac?guid=930B11B52D8045C09D9BCF572C729CF0&vkey=E3419FA265BD365086697859CB3FE52C86B1EB43319B0502111C86CF7EB61559ECEE1806A72B08E334D986D5401CE874D549EFD3BCAE5ED9&uin=1152921504616111776&redirect=1&fromtag=101114","trackType":""} Mar 30 10:49:28 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::syncState stateService pause Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:49:28 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:49:28 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:49:28 VTS volumio[23312]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85227,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"Find Your Way","artist":"In Color","album":"In Color (the Lamp Album)","uri":"http://58.222.38.160/amobile.music.tc.qq.com/F000001kl8h32gv5HN.flac?guid=930B11B52D8045C09D9BCF572C729CF0&vkey=E3419FA265BD365086697859CB3FE52C86B1EB43319B0502111C86CF7EB61559ECEE1806A72B08E334D986D5401CE874D549EFD3BCAE5ED9&uin=1152921504616111776&redirect=1&fromtag=101114","trackType":""} Mar 30 10:49:28 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::syncState stateService pause Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:49:28 VTS volumio[23312]: info: ------------------------------ 40ms Mar 30 10:49:28 VTS volumio[23312]: info: ------------------------------ 39ms Mar 30 10:49:28 VTS volumio[23312]: info: ------------------------------ 38ms Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:28 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:28 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:28 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:28 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:28 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:36 VTS nmbd[675]: [2024/03/30 10:49:36.525900, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 30 10:49:36 VTS nmbd[675]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.40 for name WORKGROUP<1d>. Mar 30 10:49:36 VTS nmbd[675]: This response was from IP 10.0.0.2, reporting an IP address of 10.0.0.2. Mar 30 10:49:51 VTS volumio[1648]: 93 Mar 30 10:49:51 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:51 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:51 VTS volumio[23312]: info: Mar 30 10:49:51 VTS volumio[23312]: ---------------------------- Client requests Volume 93 Mar 30 10:49:51 VTS volumio[23312]: info: VolumeController::SetAlsaVolume93 Mar 30 10:49:51 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:51 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:51 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:51 VTS volumio[1648]: [158B blob data] Mar 30 10:49:51 VTS volumio[1648]: {"time":1711766991621,"response":"volume Success"}93 Mar 30 10:49:51 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:51 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:51 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:52 VTS volumio[1648]: 87 Mar 30 10:49:52 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:52 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:52 VTS volumio[23312]: info: Mar 30 10:49:52 VTS volumio[23312]: ---------------------------- Client requests Volume 87 Mar 30 10:49:52 VTS volumio[23312]: info: VolumeController::SetAlsaVolume87 Mar 30 10:49:52 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:52 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:52 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:52 VTS volumio[1648]: [158B blob data] Mar 30 10:49:52 VTS volumio[1648]: {"time":1711766992681,"response":"volume Success"}87 Mar 30 10:49:52 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:52 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:52 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:53 VTS volumio[1648]: 81 Mar 30 10:49:53 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:53 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:53 VTS volumio[23312]: info: Mar 30 10:49:53 VTS volumio[23312]: ---------------------------- Client requests Volume 81 Mar 30 10:49:53 VTS volumio[23312]: info: VolumeController::SetAlsaVolume81 Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:53 VTS volumio[1648]: [158B blob data] Mar 30 10:49:53 VTS volumio[1648]: {"time":1711766993165,"response":"volume Success"}81 Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:53 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:53 VTS volumio[1648]: 75 Mar 30 10:49:53 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:53 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:53 VTS volumio[23312]: info: Mar 30 10:49:53 VTS volumio[23312]: ---------------------------- Client requests Volume 75 Mar 30 10:49:53 VTS volumio[23312]: info: VolumeController::SetAlsaVolume75 Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:53 VTS volumio[1648]: [158B blob data] Mar 30 10:49:53 VTS volumio[1648]: {"time":1711766993242,"response":"volume Success"}75 Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:53 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:53 VTS volumio[1648]: 68 Mar 30 10:49:53 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:53 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:53 VTS volumio[23312]: info: Mar 30 10:49:53 VTS volumio[23312]: ---------------------------- Client requests Volume 68 Mar 30 10:49:53 VTS volumio[23312]: info: VolumeController::SetAlsaVolume68 Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:53 VTS volumio[1648]: [158B blob data] Mar 30 10:49:53 VTS volumio[1648]: {"time":1711766993492,"response":"volume Success"}68 Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:53 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:53 VTS volumio[1648]: 62 Mar 30 10:49:53 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:53 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:53 VTS volumio[23312]: info: Mar 30 10:49:53 VTS volumio[23312]: ---------------------------- Client requests Volume 62 Mar 30 10:49:53 VTS volumio[23312]: info: VolumeController::SetAlsaVolume62 Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:53 VTS volumio[1648]: [158B blob data] Mar 30 10:49:53 VTS volumio[1648]: {"time":1711766993777,"response":"volume Success"}62 Mar 30 10:49:53 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:53 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:53 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:54 VTS volumio[1648]: 56 Mar 30 10:49:54 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:54 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:54 VTS volumio[23312]: info: Mar 30 10:49:54 VTS volumio[23312]: ---------------------------- Client requests Volume 56 Mar 30 10:49:54 VTS volumio[23312]: info: VolumeController::SetAlsaVolume56 Mar 30 10:49:54 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:54 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:54 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:54 VTS volumio[1648]: [158B blob data] Mar 30 10:49:54 VTS volumio[1648]: {"time":1711766994077,"response":"volume Success"}56 Mar 30 10:49:54 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:54 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:54 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:54 VTS volumio[1648]: 50 Mar 30 10:49:54 VTS volumio[1648]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 30 10:49:54 VTS volumio[1648]: Dload Upload Total Spent Left Speed Mar 30 10:49:54 VTS volumio[23312]: info: Mar 30 10:49:54 VTS volumio[23312]: ---------------------------- Client requests Volume 50 Mar 30 10:49:54 VTS volumio[23312]: info: VolumeController::SetAlsaVolume50 Mar 30 10:49:54 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:49:54 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:49:54 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:49:54 VTS volumio[1648]: [237B blob data] Mar 30 10:49:54 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:49:54 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:49:54 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:49:54 VTS volumio[1648]: {"time":1711766994406,"response":"volume Success"}50 Mar 30 10:50:09 VTS volumio[23312]: info: Clearing queue after UPNP request Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::ClearQueue Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::stPlaybackTimer Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::updateTrackBlock Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrackBlock Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::serviceStop Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::stop Mar 30 10:50:09 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand stop Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::clearPlayQueue Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::saveQueue Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushQueue Mar 30 10:50:09 VTS volumio[23312]: info: Mar 30 10:50:09 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:09 VTS volumio[23312]: info: sendMpdCommand stop took 20 milliseconds Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:09 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:09 VTS volumio[23312]: info: Mar 30 10:50:09 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:09 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:09 VTS volumio[23312]: info: sendMpdCommand status took 6 milliseconds Mar 30 10:50:09 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 30 10:50:09 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::syncState stateService stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: No code Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: ------------------------------ 39ms Mar 30 10:50:09 VTS volumio[23312]: info: Mar 30 10:50:09 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:09 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:09 VTS volumio[23312]: info: Mar 30 10:50:09 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:09 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:09 VTS volumio[23312]: error: updateQueue error: null Mar 30 10:50:09 VTS volumio[23312]: info: ------------------------------ 44ms Mar 30 10:50:09 VTS volumio[23312]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:50:09 VTS volumio[23312]: info: sendMpdCommand status took 3 milliseconds Mar 30 10:50:09 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 30 10:50:09 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::syncState stateService stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: No code Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 30 10:50:09 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::syncState stateService stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: No code Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:09 VTS volumio[23312]: info: ------------------------------ 71ms Mar 30 10:50:09 VTS volumio[23312]: info: ------------------------------ 68ms Mar 30 10:50:09 VTS volumio[23312]: info: Mar 30 10:50:09 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:09 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:09 VTS volumio[23312]: info: Mar 30 10:50:09 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:09 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:09 VTS volumio[23312]: error: updateQueue error: null Mar 30 10:50:09 VTS volumio[23312]: error: updateQueue error: null Mar 30 10:50:09 VTS volumio[23312]: info: ------------------------------ 34ms Mar 30 10:50:09 VTS volumio[23312]: info: ------------------------------ 33ms Mar 30 10:50:09 VTS volumio[23312]: info: Starting UPNP Playback Mar 30 10:50:09 VTS volumio[23312]: info: Preparing playback through UPNP Mar 30 10:50:09 VTS volumio[23312]: info: CoreCommandRouter::volumioGetState Mar 30 10:50:09 VTS volumio[23312]: info: CorePlayQueue::getTrack 0 Mar 30 10:50:09 VTS volumio[23312]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 3ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 3ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 1ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 3ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 1ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 3ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 1ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 3ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 3ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 1ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 2ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 3 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 2 milliseconds Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 8 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 4 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 3 milliseconds Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE).mp3","artist":null,"album":null,"uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus stop Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE).mp3","artist":null,"album":null,"uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE).mp3","artist":null,"album":null,"uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 34ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 77ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 76ms Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 66 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 65 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 66 milliseconds Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE).mp3","artist":null,"album":null,"uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE)","artist":"4 IN LOVE","album":"成名曲精选集 2010","uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE)","artist":"4 IN LOVE","album":"成名曲精选集 2010","uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 156ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 153ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 153ms Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces system playlist update Mar 30 10:50:10 VTS volumio[23312]: info: Ignoring MPD Status Update Mar 30 10:50:10 VTS volumio[23312]: info: Mar 30 10:50:10 VTS volumio[23312]: ---------------------------- MPD announces state update: player Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::getState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand status Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 129ms Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 128 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 28ms Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 27 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 23ms Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand status took 21 milliseconds Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseState Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 30 10:50:10 VTS volumio[23312]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: verbose: ControllerMpd::parseTrackInfo Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE)","artist":"4 IN LOVE","album":"成名曲精选集 2010","uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE)","artist":"4 IN LOVE","album":"成名曲精选集 2010","uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ControllerMpd::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::servicePushState Mar 30 10:50:10 VTS volumio[23312]: verbose: In UPNP mode Mar 30 10:50:10 VTS volumio[23312]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"31 一千零一个愿望(4 IN LOVE)","artist":"4 IN LOVE","album":"成名曲精选集 2010","uri":"http://10.0.0.13:54156/.upnp/1.mp3","trackType":"mp3"} Mar 30 10:50:10 VTS volumio[23312]: verbose: CURRENT POSITION 0 Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState stateService play Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::syncState currentStatus play Mar 30 10:50:10 VTS volumio[23312]: info: Received an update from plugin. extracting info from payload Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::pushState Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioPushState Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 184ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 82ms Mar 30 10:50:10 VTS volumio[23312]: info: ------------------------------ 78ms Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreCommandRouter::volumioGetQueue Mar 30 10:50:10 VTS volumio[23312]: info: CoreStateMachine::getQueue Mar 30 10:50:10 VTS volumio[23312]: info: CorePlayQueue::getQueue Mar 30 10:50:12 VTS volumio[23312]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 10:50:12 VTS volumio[23312]: Error: connect ETIMEDOUT 199.59.149.203:443 Mar 30 10:50:12 VTS volumio[23312]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 30 10:50:12 VTS volumio[23312]: errno: -110, Mar 30 10:50:12 VTS volumio[23312]: code: 'ETIMEDOUT', Mar 30 10:50:12 VTS volumio[23312]: syscall: 'connect', Mar 30 10:50:12 VTS volumio[23312]: address: '199.59.149.203', Mar 30 10:50:12 VTS volumio[23312]: port: 443 Mar 30 10:50:12 VTS volumio[23312]: } Mar 30 10:50:12 VTS volumio[23312]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 10:50:13 VTS sudo[24622]: volumio : unable to resolve host VTS Mar 30 10:50:13 VTS sudo[24622]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 30 10:50:13 VTS sudo[24622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 10:49 Mar 30 10:50:13 VTS sudo[24622]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"