-- Logs begin at Wed 2021-12-01 13:09:13 UTC, end at Fri 2024-05-10 07:43:20 UTC. -- May 10 07:43:01 opera volumio[21277]: info: May 10 07:43:01 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:01 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:01 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:01 opera volumio[21277]: info: sendMpdCommand status took 1 milliseconds May 10 07:43:01 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:01 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:01 opera volumio[21277]: info: sendMpdCommand playlistinfo took 1 milliseconds May 10 07:43:01 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:01 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:01 opera volumio[21277]: 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":"Symphony of the seas","artist":"Royal Philharmonic Orchestra","album":"皇家爱乐乐团演奏名曲第三辑","uri":"http://183.240.185.72/amobile.music.tc.qq.com/F0000043q5zo2IBQjk.flac?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=30661619ABC4198F9748E596417DB26072DE6CC49A68044D09C2A95596B60C90FFE4F4AF8F8F711A5FA06F5ADB149AEEDAC46A1C44170050&uin=1152921504950983260&redirect=1&fromtag=111114","trackType":""} May 10 07:43:01 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::syncState stateService stop May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus play May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::play index undefined May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:01 opera volumio[21277]: info: ------------------------------ 16ms May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::volumioGetQueue May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::getQueue May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::getQueue May 10 07:43:01 opera volumio[21277]: info: Clearing queue after UPNP request May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::ClearQueue May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::stop May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 07:43:01 opera volumio[21277]: verbose: UNSET VOLATILE May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::clearPlayQueue May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::saveQueue May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::volumioPushQueue May 10 07:43:01 opera volumio[21277]: info: May 10 07:43:01 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:01 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:01 opera volumio[21277]: error: updateQueue error: null May 10 07:43:01 opera volumio[21277]: info: ------------------------------ 2ms May 10 07:43:01 opera volumio[21277]: info: Starting UPNP Playback May 10 07:43:01 opera volumio[21277]: info: Preparing playback through UPNP May 10 07:43:01 opera volumio[21277]: info: CoreCommandRouter::volumioGetState May 10 07:43:01 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:01 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService mpd May 10 07:43:01 opera volumio[21277]: info: May 10 07:43:01 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:01 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:01 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:01 opera volumio[21277]: info: May 10 07:43:01 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:01 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:01 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:01 opera volumio[21277]: info: May 10 07:43:01 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:01 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:02 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:02 opera volumio[21277]: info: May 10 07:43:02 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:02 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:02 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:02 opera volumio[21277]: info: May 10 07:43:02 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:02 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:02 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:02 opera volumio[21277]: info: May 10 07:43:02 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:02 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:02 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:02 opera volumio[21277]: info: May 10 07:43:02 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:02 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:02 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:02 opera volumio[21277]: info: sendMpdCommand status took 2 milliseconds May 10 07:43:02 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:02 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:02 opera volumio[21277]: info: sendMpdCommand playlistinfo took 1 milliseconds May 10 07:43:02 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:02 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:02 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:02 opera volumio[21277]: 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":"Question","artist":"Stray Kids (스트레이 키즈)","album":"I am WHO","uri":"http://120.234.70.147/amobile.music.tc.qq.com/F000002cAbgt0FKeKL.flac?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=CC3A0E991707DD34D7D04E6F350F07D33C584A57E86E21EFAF542F57F7CA1C028F8980B00B7CAE1325722B48D1F5B63A7A8658D2A7ADE57E&uin=1152921504950983260&src=F000002cAbgt0FKeKL.flac&redirect=1&fromtag=111114","trackType":""} May 10 07:43:02 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::syncState stateService stop May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus stop May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:02 opera volumio[21277]: info: No code May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:02 opera volumio[21277]: info: ------------------------------ 22ms May 10 07:43:02 opera volumio[21277]: info: Clearing queue after UPNP request May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::ClearQueue May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::stop May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 07:43:02 opera volumio[21277]: verbose: UNSET VOLATILE May 10 07:43:02 opera volumio[21277]: info: CorePlayQueue::clearPlayQueue May 10 07:43:02 opera volumio[21277]: info: CorePlayQueue::saveQueue May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::volumioPushQueue May 10 07:43:02 opera volumio[21277]: info: May 10 07:43:02 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:02 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:02 opera volumio[21277]: error: updateQueue error: null May 10 07:43:02 opera volumio[21277]: info: ------------------------------ 2ms May 10 07:43:02 opera volumio[21277]: info: Starting UPNP Playback May 10 07:43:02 opera volumio[21277]: info: Preparing playback through UPNP May 10 07:43:02 opera volumio[21277]: info: CoreCommandRouter::volumioGetState May 10 07:43:02 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:02 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService mpd May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 2ms May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 0ms May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:03 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:03 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:03 opera volumio[21277]: info: May 10 07:43:03 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:03 opera volumio[21277]: info: sendMpdCommand status took 4 milliseconds May 10 07:43:03 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 2ms May 10 07:43:03 opera volumio[21277]: info: sendMpdCommand status took 2 milliseconds May 10 07:43:03 opera volumio[21277]: info: sendMpdCommand playlistinfo took 2 milliseconds May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:03 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:03 opera volumio[21277]: verbose: In UPNP mode May 10 07:43:03 opera volumio[21277]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tennesse Waltz","artist":"Kelvin Williams","album":"Romance Violin Instrumental, Vol. 2","uri":"http://120.234.70.147/amobile.music.tc.qq.com/M500003Ymkvy4GmY5C.mp3?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=9B6A94A08754E669E6304ADCF16EFA9EDF0BBD6A1A97FFA79A9AC96DE3A76D209EFBF5A4C6CFC61FF3486C40B0287F7FB9C87DBC1ACED8DF&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} May 10 07:43:03 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::syncState stateService play May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus stop May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 24ms May 10 07:43:03 opera volumio[21277]: info: sendMpdCommand playlistinfo took 17 milliseconds May 10 07:43:03 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:03 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:03 opera volumio[21277]: verbose: In UPNP mode May 10 07:43:03 opera volumio[21277]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tennesse Waltz","artist":"Kelvin Williams","album":"Romance Violin Instrumental, Vol. 2","uri":"http://120.234.70.147/amobile.music.tc.qq.com/M500003Ymkvy4GmY5C.mp3?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=9B6A94A08754E669E6304ADCF16EFA9EDF0BBD6A1A97FFA79A9AC96DE3A76D209EFBF5A4C6CFC61FF3486C40B0287F7FB9C87DBC1ACED8DF&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} May 10 07:43:03 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::syncState stateService play May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus play May 10 07:43:03 opera volumio[21277]: info: Received an update from plugin. extracting info from payload May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:03 opera volumio[21277]: info: ------------------------------ 34ms May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::volumioGetQueue May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::getQueue May 10 07:43:03 opera volumio[21277]: info: CorePlayQueue::getQueue May 10 07:43:03 opera volumio[21277]: info: CoreCommandRouter::volumioGetQueue May 10 07:43:03 opera volumio[21277]: info: CoreStateMachine::getQueue May 10 07:43:03 opera volumio[21277]: info: CorePlayQueue::getQueue May 10 07:43:06 opera volumio[21277]: info: May 10 07:43:06 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:06 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:06 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:06 opera volumio[21277]: info: sendMpdCommand status took 1 milliseconds May 10 07:43:06 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:06 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:06 opera volumio[21277]: info: sendMpdCommand playlistinfo took 1 milliseconds May 10 07:43:06 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:06 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:06 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:06 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:06 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:06 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:06 opera volumio[21277]: 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":"Tennesse Waltz","artist":"Kelvin Williams","album":"Romance Violin Instrumental, Vol. 2","uri":"http://120.234.70.147/amobile.music.tc.qq.com/M500003Ymkvy4GmY5C.mp3?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=9B6A94A08754E669E6304ADCF16EFA9EDF0BBD6A1A97FFA79A9AC96DE3A76D209EFBF5A4C6CFC61FF3486C40B0287F7FB9C87DBC1ACED8DF&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} May 10 07:43:06 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::syncState stateService stop May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus play May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::play index undefined May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:06 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:06 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:06 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:06 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:06 opera volumio[21277]: info: ------------------------------ 14ms May 10 07:43:06 opera volumio[21277]: info: CoreCommandRouter::volumioGetQueue May 10 07:43:06 opera volumio[21277]: info: CoreStateMachine::getQueue May 10 07:43:06 opera volumio[21277]: info: CorePlayQueue::getQueue May 10 07:43:07 opera volumio[21277]: info: Clearing queue after UPNP request May 10 07:43:07 opera volumio[21277]: info: CoreStateMachine::ClearQueue May 10 07:43:07 opera volumio[21277]: info: CoreStateMachine::stop May 10 07:43:07 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 07:43:07 opera volumio[21277]: verbose: UNSET VOLATILE May 10 07:43:07 opera volumio[21277]: info: CorePlayQueue::clearPlayQueue May 10 07:43:07 opera volumio[21277]: info: CorePlayQueue::saveQueue May 10 07:43:07 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:07 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:07 opera volumio[21277]: info: CoreCommandRouter::volumioPushQueue May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: error: updateQueue error: null May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 2ms May 10 07:43:07 opera volumio[21277]: info: Starting UPNP Playback May 10 07:43:07 opera volumio[21277]: info: Preparing playback through UPNP May 10 07:43:07 opera volumio[21277]: info: CoreCommandRouter::volumioGetState May 10 07:43:07 opera volumio[21277]: info: CorePlayQueue::getTrack 0 May 10 07:43:07 opera volumio[21277]: info: CoreStateMachine::setConsumeUpdateService mpd May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 2ms May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 0ms May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:07 opera volumio[21277]: info: May 10 07:43:07 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:07 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:07 opera volumio[21277]: info: ------------------------------ 1ms May 10 07:43:08 opera volumio[21277]: info: May 10 07:43:08 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:08 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:08 opera volumio[21277]: info: May 10 07:43:08 opera volumio[21277]: ---------------------------- MPD announces state update: player May 10 07:43:08 opera volumio[21277]: info: sendMpdCommand status took 2 milliseconds May 10 07:43:08 opera volumio[21277]: info: ControllerMpd::getState May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand status May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:08 opera volumio[21277]: info: May 10 07:43:08 opera volumio[21277]: ---------------------------- MPD announces system playlist update May 10 07:43:08 opera volumio[21277]: info: Ignoring MPD Status Update May 10 07:43:08 opera volumio[21277]: info: sendMpdCommand status took 3 milliseconds May 10 07:43:08 opera volumio[21277]: info: sendMpdCommand playlistinfo took 2 milliseconds May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::parseState May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:08 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:08 opera volumio[21277]: verbose: In UPNP mode May 10 07:43:08 opera volumio[21277]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Questions","artist":"Sinco","album":"Questions","uri":"http://120.234.70.147/amobile.music.tc.qq.com/M500000Bdxf608mRgy.mp3?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=FC84B8353B9B74232A4108EEFF264A8EF78475D93BB34F14A8781704D2290C1E9F26EF36FE63CBA4155CD432CE30997292DF88DBE983483D&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} May 10 07:43:08 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::syncState stateService play May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus stop May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:08 opera volumio[21277]: info: ------------------------------ 20ms May 10 07:43:08 opera volumio[21277]: info: ------------------------------ 16ms May 10 07:43:08 opera volumio[21277]: info: sendMpdCommand playlistinfo took 15 milliseconds May 10 07:43:08 opera volumio[21277]: verbose: ControllerMpd::parseTrackInfo May 10 07:43:08 opera volumio[21277]: info: ControllerMpd::pushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::servicePushState May 10 07:43:08 opera volumio[21277]: verbose: In UPNP mode May 10 07:43:08 opera volumio[21277]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Questions","artist":"Sinco","album":"Questions","uri":"http://120.234.70.147/amobile.music.tc.qq.com/M500000Bdxf608mRgy.mp3?guid=ffffffffd44d833d0000018c2f3bf5bf&vkey=FC84B8353B9B74232A4108EEFF264A8EF78475D93BB34F14A8781704D2290C1E9F26EF36FE63CBA4155CD432CE30997292DF88DBE983483D&uin=1152921504950983260&redirect=1&fromtag=111042","trackType":""} May 10 07:43:08 opera volumio[21277]: verbose: CURRENT POSITION 0 May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::syncState stateService play May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::syncState currentStatus play May 10 07:43:08 opera volumio[21277]: info: Received an update from plugin. extracting info from payload May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::pushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::volumioPushState May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 07:43:08 opera volumio[21277]: info: ------------------------------ 37ms May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::volumioGetQueue May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::getQueue May 10 07:43:08 opera volumio[21277]: info: CorePlayQueue::getQueue May 10 07:43:08 opera volumio[21277]: info: CoreCommandRouter::volumioGetQueue May 10 07:43:08 opera volumio[21277]: info: CoreStateMachine::getQueue May 10 07:43:08 opera volumio[21277]: info: CorePlayQueue::getQueue May 10 07:43:20 opera volumio[21277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 07:43:20 opera volumio[21277]: { Error: connect ETIMEDOUT 199.59.148.202:443 May 10 07:43:20 opera volumio[21277]: at Object._errnoException (util.js:1022:11) May 10 07:43:20 opera volumio[21277]: at _exceptionWithHostPort (util.js:1044:20) May 10 07:43:20 opera volumio[21277]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 10 07:43:20 opera volumio[21277]: code: 'ETIMEDOUT', May 10 07:43:20 opera volumio[21277]: errno: 'ETIMEDOUT', May 10 07:43:20 opera volumio[21277]: syscall: 'connect', May 10 07:43:20 opera volumio[21277]: address: '199.59.148.202', May 10 07:43:20 opera volumio[21277]: port: 443 } May 10 07:43:20 opera volumio[21277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 07:43:20 opera sudo[24061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-10 07:42 May 10 07:43:20 opera sudo[24061]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 16:18:39 CEST 2021" VOLUMIO_VERSION="1.031" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="8579bfbfd528c599f57acb0dd7167775"