-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Mon 2025-09-08 20:40:55 HKT. -- Sep 08 20:39:03 oscar ntpd[820]: Soliciting pool server 193.182.111.143 Sep 08 20:39:06 oscar ntpd[820]: Soliciting pool server 162.159.200.123 Sep 08 20:39:06 oscar ntpd[820]: Soliciting pool server 193.182.111.141 Sep 08 20:39:06 oscar ntpd[820]: Soliciting pool server 193.182.111.14 Sep 08 20:39:07 oscar ntpd[820]: Soliciting pool server 162.159.200.1 Sep 08 20:39:07 oscar ntpd[820]: Soliciting pool server 139.199.214.202 Sep 08 20:39:08 oscar ntpd[820]: Soliciting pool server 139.199.215.251 Sep 08 20:39:09 oscar ntpd[820]: Soliciting pool server 84.16.67.12 Sep 08 20:39:15 oscar ntpd[820]: receive: Unexpected origin timestamp 0xec694cf2.79c04831 does not match aorg 0000000000.00000000 from server@193.182.111.14 xmt 0xec694cf3.4a7694f6 Sep 08 20:39:18 oscar volumio[900]: info: Sep 08 20:39:18 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:18 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:18 oscar volumio[900]: info: Sep 08 20:39:18 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:18 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:18 oscar volumio[900]: info: Sep 08 20:39:18 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:18 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:18 oscar volumio[900]: info: sendMpdCommand status took 2 milliseconds Sep 08 20:39:18 oscar volumio[900]: info: sendMpdCommand status took 2 milliseconds Sep 08 20:39:18 oscar volumio[900]: info: sendMpdCommand status took 1 milliseconds Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:18 oscar kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Sep 08 20:39:18 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:39:18 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:39:18 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:18 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:18 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:39:18 oscar volumio[900]: 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":"Rachmaninoff: Piano Concerto No. 1 in F Sharp Minor, Op. 1 - 1. Vivace","artist":"Vladimir Ashkenazy","album":"Rachmaninov: The Complete Recordings","uri":"https://sjy6.stream.qqmusic.qq.com/M800000jumPg1iwb6e.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=328777BE52C89AC583CE8656895B19E9987A7308CF99D00470D39A2F1518D7852BB09C94719A39279FD9E931BEF2D304C129F78EF426720B__v2b9aba47&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:18 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::syncState stateService stop Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::stPlaybackTimer Sep 08 20:39:18 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:39:18 oscar volumio[900]: 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":"Rachmaninoff: Piano Concerto No. 1 in F Sharp Minor, Op. 1 - 1. Vivace","artist":"Vladimir Ashkenazy","album":"Rachmaninov: The Complete Recordings","uri":"https://sjy6.stream.qqmusic.qq.com/M800000jumPg1iwb6e.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=328777BE52C89AC583CE8656895B19E9987A7308CF99D00470D39A2F1518D7852BB09C94719A39279FD9E931BEF2D304C129F78EF426720B__v2b9aba47&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:18 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::syncState stateService stop Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus stop Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: No code Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:39:18 oscar volumio[900]: 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":"Rachmaninoff: Piano Concerto No. 1 in F Sharp Minor, Op. 1 - 1. Vivace","artist":"Vladimir Ashkenazy","album":"Rachmaninov: The Complete Recordings","uri":"https://sjy6.stream.qqmusic.qq.com/M800000jumPg1iwb6e.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=328777BE52C89AC583CE8656895B19E9987A7308CF99D00470D39A2F1518D7852BB09C94719A39279FD9E931BEF2D304C129F78EF426720B__v2b9aba47&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:18 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::syncState stateService stop Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus stop Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: No code Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: ------------------------------ 46ms Sep 08 20:39:18 oscar volumio[900]: info: ------------------------------ 45ms Sep 08 20:39:18 oscar volumio[900]: info: ------------------------------ 45ms Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:18 oscar volumio[900]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Vladimir%20Ashkenazy/Rachmaninov%3A%20The%20Complete%20Recordings/aad2af08-4122-4d25-9161-71c6dbef9770.jpg' Sep 08 20:39:18 oscar volumio[900]: info: Clearing queue after UPNP request Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::ClearQueue Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::stop Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::clearPlayQueue Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::saveQueue Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioPushQueue Sep 08 20:39:18 oscar volumio[900]: info: Sep 08 20:39:18 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:18 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:18 oscar volumio[900]: info: Sep 08 20:39:18 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:18 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:18 oscar volumio[900]: info: Sep 08 20:39:18 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:18 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:18 oscar volumio[900]: error: updateQueue error: null Sep 08 20:39:18 oscar volumio[900]: error: updateQueue error: null Sep 08 20:39:18 oscar volumio[900]: error: updateQueue error: null Sep 08 20:39:18 oscar volumio[900]: info: ------------------------------ 4ms Sep 08 20:39:18 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:39:18 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:18 oscar volumio[900]: info: Starting UPNP Playback Sep 08 20:39:18 oscar volumio[900]: info: Preparing playback through UPNP Sep 08 20:39:18 oscar volumio[900]: info: CoreCommandRouter::volumioGetState Sep 08 20:39:18 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:39:18 oscar volumio[900]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 4 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 3 milliseconds Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 7 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 5 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 4 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 3 milliseconds Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando (C小调第2号钢琴协奏曲 作品18 - 라흐마니노프: 피아노 협주곡 다단조, 작품번호 18: 3. Allegro scherzando|ピアノキョウソウキョクダイニバン: アレグロスケルツァンド|ピアノ協奏曲 第2番 ハ短調 作品18: 第3楽章: Allegro scherzando)","artist":"Vladimir Ashkenazy/London Symphony Orchestra/André Previn/Niccolò Paganini","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus stop Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando (C小调第2号钢琴协奏曲 作品18 - 라흐마니노프: 피아노 협주곡 다단조, 작품번호 18: 3. Allegro scherzando|ピアノキョウソウキョクダイニバン: アレグロスケルツァンド|ピアノ協奏曲 第2番 ハ短調 作品18: 第3楽章: Allegro scherzando)","artist":"Vladimir Ashkenazy/London Symphony Orchestra/André Previn/Niccolò Paganini","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 23ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 31ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando (C小调第2号钢琴协奏曲 作品18 - 라흐마니노프: 피아노 협주곡 다단조, 작품번호 18: 3. Allegro scherzando|ピアノキョウソウキョクダイニバン: アレグロスケルツァンド|ピアノ協奏曲 第2番 ハ短調 作品18: 第3楽章: Allegro scherzando)","artist":"Vladimir Ashkenazy/London Symphony Orchestra/André Previn/Niccolò Paganini","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando (C小调第2号钢琴协奏曲 作品18 - 라흐마니노프: 피아노 협주곡 다단조, 작품번호 18: 3. Allegro scherzando|ピアノキョウソウキョクダイニバン: アレグロスケルツァンド|ピアノ協奏曲 第2番 ハ短調 作品18: 第3楽章: Allegro scherzando)","artist":"Vladimir Ashkenazy/London Symphony Orchestra/André Previn/Niccolò Paganini","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 99ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 98ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 97ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 96ms Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:39:19 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:39:19 oscar volumio[900]: info: Sep 08 20:39:19 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 85ms Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 84 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 16ms Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 16 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 16ms Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand status took 16 milliseconds Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:39:19 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:39:19 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:39:19 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":695,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:39:19 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:39:19 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 125ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 56ms Sep 08 20:39:19 oscar volumio[900]: info: ------------------------------ 54ms Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:39:19 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:39:19 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:39:28 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 20:39:28 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 20:39:28 oscar volumio[900]: info: Discovery: Getting this device information Sep 08 20:39:28 oscar volumio[900]: info: CoreCommandRouter::volumioGetState Sep 08 20:39:28 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 20:39:48 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 20:39:48 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 20:39:48 oscar volumio[900]: info: Discovery: Getting this device information Sep 08 20:39:48 oscar volumio[900]: info: CoreCommandRouter::volumioGetState Sep 08 20:39:48 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 20:39:48 oscar volumio[900]: verbose: New Socket.io Connection to 192.168.101.133:3000 from 192.168.101.50 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 08 20:39:48 oscar volumio[900]: verbose: New Socket.io Connection to 192.168.101.133:3000 from 192.168.101.50 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 08 20:40:02 oscar volumio[900]: info: Sep 08 20:40:02 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:02 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:02 oscar volumio[900]: info: Sep 08 20:40:02 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:02 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:02 oscar volumio[900]: info: Sep 08 20:40:02 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:02 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand status took 6 milliseconds Sep 08 20:40:02 oscar kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand status took 6 milliseconds Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand status took 5 milliseconds Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand clearerror took 6 milliseconds Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand clearerror took 5 milliseconds Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand clearerror took 6 milliseconds Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:40:02 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:02 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:02 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:40:02 oscar volumio[900]: 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":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:02 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::syncState stateService stop Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::stPlaybackTimer Sep 08 20:40:02 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:40:02 oscar volumio[900]: 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":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:02 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::syncState stateService stop Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus stop Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: No code Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:40:02 oscar volumio[900]: 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":"Rachmaninoff: Piano Concerto No. 2 in C minor, Op. 18 - 3. Allegro scherzando","artist":"Vladimir Ashkenazy","album":"Rachmaninov: Piano Concerto No.2; Rhapsody on A Theme of Paganini","uri":"https://sjy6.stream.qqmusic.qq.com/M800001820hr46DG2s.mp3?guid=ffffffff8d32393f000001892bb7edaf&vkey=B534E5BA92F1A68E026550DC21B9655F0440D60EBDA958843063C095D46A615F27AB2A3CD278788EBB489195F5425E3918F014349F56BB9B__v21e2a17cf&uin=911219607&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:02 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::syncState stateService stop Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus stop Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: No code Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:02 oscar volumio[900]: info: ------------------------------ 52ms Sep 08 20:40:02 oscar volumio[900]: info: ------------------------------ 51ms Sep 08 20:40:02 oscar volumio[900]: info: ------------------------------ 51ms Sep 08 20:40:02 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:02 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:02 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:02 oscar volumio[900]: info: Clearing queue after UPNP request Sep 08 20:40:03 oscar volumio[900]: info: CoreStateMachine::ClearQueue Sep 08 20:40:03 oscar volumio[900]: info: CoreStateMachine::stop Sep 08 20:40:03 oscar volumio[900]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 20:40:03 oscar volumio[900]: info: CorePlayQueue::clearPlayQueue Sep 08 20:40:03 oscar volumio[900]: info: CorePlayQueue::saveQueue Sep 08 20:40:03 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:03 oscar volumio[900]: info: CoreCommandRouter::volumioPushQueue Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: error: updateQueue error: null Sep 08 20:40:03 oscar volumio[900]: error: updateQueue error: null Sep 08 20:40:03 oscar volumio[900]: error: updateQueue error: null Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 5ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 4ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 4ms Sep 08 20:40:03 oscar volumio[900]: info: Starting UPNP Playback Sep 08 20:40:03 oscar volumio[900]: info: Preparing playback through UPNP Sep 08 20:40:03 oscar volumio[900]: info: CoreCommandRouter::volumioGetState Sep 08 20:40:03 oscar volumio[900]: info: CorePlayQueue::getTrack 0 Sep 08 20:40:03 oscar volumio[900]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 3ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: Sep 08 20:40:03 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:03 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 2ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:40:03 oscar volumio[900]: info: ------------------------------ 1ms Sep 08 20:40:04 oscar volumio[900]: info: Sep 08 20:40:04 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:04 oscar volumio[900]: info: Sep 08 20:40:04 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:04 oscar kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Sep 08 20:40:04 oscar volumio[900]: info: Sep 08 20:40:04 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:04 oscar volumio[900]: info: Sep 08 20:40:04 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand status took 3 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand status took 3 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand status took 2 milliseconds Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:04 oscar volumio[900]: info: Sep 08 20:40:04 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:04 oscar volumio[900]: info: Sep 08 20:40:04 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand status took 4 milliseconds Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand status took 3 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand status took 4 milliseconds Sep 08 20:40:04 oscar volumio[900]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:04 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:04 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:04 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:04 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus stop Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:04 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:04 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:04 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:04 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:04 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:04 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:04 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:04 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:04 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:04 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:04 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:04 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:04 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:04 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:04 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 29ms Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 45ms Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 44ms Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 43ms Sep 08 20:40:05 oscar volumio[900]: info: Sep 08 20:40:05 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:05 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:05 oscar volumio[900]: info: Sep 08 20:40:05 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:05 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:05 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:05 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:05 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:05 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:05 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:05 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:05 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 58ms Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 58ms Sep 08 20:40:05 oscar volumio[900]: info: Sep 08 20:40:05 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:05 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:05 oscar volumio[900]: info: Sep 08 20:40:05 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:05 oscar volumio[900]: info: Sep 08 20:40:05 oscar volumio[900]: ---------------------------- MPD announces system playlist update Sep 08 20:40:05 oscar volumio[900]: info: Ignoring MPD Status Update Sep 08 20:40:05 oscar volumio[900]: info: Sep 08 20:40:05 oscar volumio[900]: ---------------------------- MPD announces state update: player Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::getState Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand status Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 36ms Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand status took 35 milliseconds Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 18ms Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand status took 17 milliseconds Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 17ms Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand status took 16 milliseconds Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::parseState Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 08 20:40:05 oscar volumio[900]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 08 20:40:05 oscar volumio[900]: verbose: ControllerMpd::parseTrackInfo Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:05 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:05 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:05 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:05 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:05 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:05 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:05 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:05 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: ControllerMpd::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::servicePushState Sep 08 20:40:05 oscar volumio[900]: verbose: In UPNP mode Sep 08 20:40:05 oscar volumio[900]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Breathe Again","artist":"Toni Braxton","album":"The Essential Toni Braxton","uri":"https://sjy6.stream.qqmusic.qq.com/M800002uT7OF2orR14.mp3?guid=ffffffffcd80ba82000001945a3ff5af&vkey=4AE0FF54987E67A98B9AF8B4316EF38886F044C3236ECE473C92F9401F6D81959957969C8DDD5A458D2EE51ECB91717DA477232203DCE38F__v2b9aba48&uin=911219607&src=M800004GEyeb0UkAuT.mp3&redirect=1&fromtag=111093","trackType":""} Sep 08 20:40:05 oscar volumio[900]: verbose: CURRENT POSITION 0 Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState stateService play Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::syncState currentStatus play Sep 08 20:40:05 oscar volumio[900]: info: Received an update from plugin. extracting info from payload Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::pushState Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioPushState Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 70ms Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 51ms Sep 08 20:40:05 oscar volumio[900]: info: ------------------------------ 51ms Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreCommandRouter::volumioGetQueue Sep 08 20:40:05 oscar volumio[900]: info: CoreStateMachine::getQueue Sep 08 20:40:05 oscar volumio[900]: info: CorePlayQueue::getQueue Sep 08 20:40:09 oscar ntpd[820]: Soliciting pool server 202.112.29.82 Sep 08 20:40:13 oscar ntpd[820]: Soliciting pool server 5.79.108.34 Sep 08 20:40:14 oscar ntpd[820]: Soliciting pool server 240e:92c:0:8000::72:a7 Sep 08 20:40:15 oscar ntpd[820]: Soliciting pool server 84.16.73.33 Sep 08 20:40:19 oscar ntpd[820]: Soliciting pool server 84.16.67.12 Sep 08 20:40:55 oscar volumio[900]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 20:40:55 oscar volumio[900]: Error: connect ETIMEDOUT 162.125.2.5:443 Sep 08 20:40:55 oscar volumio[900]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 08 20:40:55 oscar volumio[900]: errno: -110, Sep 08 20:40:55 oscar volumio[900]: code: 'ETIMEDOUT', Sep 08 20:40:55 oscar volumio[900]: syscall: 'connect', Sep 08 20:40:55 oscar volumio[900]: address: '162.125.2.5', Sep 08 20:40:55 oscar volumio[900]: port: 443 Sep 08 20:40:55 oscar volumio[900]: } Sep 08 20:40:55 oscar volumio[900]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 20:40:55 oscar sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 20:39 Sep 08 20:40:55 oscar sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"