-- Logs begin at Fri 2022-04-08 15:29:57 UTC, end at Fri 2025-10-17 05:56:45 UTC. -- Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::ClearQueue Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::stop Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 05:55:15 opera volumio[1182]: verbose: UNSET VOLATILE Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::stPlaybackTimer Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::updateTrackBlock Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrackBlock Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::serviceStop Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::serviceStop Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::stop Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::clearPlayQueue Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::saveQueue Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushQueue Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::addQueueItems Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::addQueueItems Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/03 -Miss You Much.dff Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/03 -Miss You Much.dff in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/12 384K-Never Know.wav Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/12 384K-Never Know.wav in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/128 - Overture.dff Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/128 - Overture.dff in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/DSD64- Suite No. 1 (for Orchestra) Balletto detto Il Conte Orlan.dff Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/DSD64- Suite No. 1 (for Orchestra) Balletto detto Il Conte Orlan.dff in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/Track04.wav Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/Track04.wav in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/Wechseldominante - Babel (DSD512).dff Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/Wechseldominante - Babel (DSD512).dff in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/宋小睿 - 点歌的人.mp3 Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/宋小睿 - 点歌的人.mp3 in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/13.老人与海.mp3 Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/13.老人与海.mp3 in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/16 96k A Song For Me.flac Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/16 96k A Song For Me.flac in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/08 Hanson Praise Ye the Lord.dsf Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/08 Hanson Praise Ye the Lord.dsf in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/256- Hanson Praise Ye the Lord.dsf Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/256- Hanson Praise Ye the Lord.dsf in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/352.8K-Violin Concerto in E minor, Op. 64- I. Allegro molto appassionato.flac Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/352.8K-Violin Concerto in E minor, Op. 64- I. Allegro molto appassionato.flac in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/48kThe Corregidor.flac Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/48kThe Corregidor.flac in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/轩东-碎银几两.flac Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/轩东-碎银几两.flac in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Adding Item to queue: music-library/USB/M2/MUSIC/阿杜-离别.flac Oct 17 05:55:15 opera volumio[1182]: info: Exploding uri music-library/USB/M2/MUSIC/阿杜-离别.flac in service mpd Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand stop took 150 milliseconds Oct 17 05:55:15 opera volumio[1182]: info: Oct 17 05:55:15 opera volumio[1182]: ---------------------------- MPD announces state update: player Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F03%20-Miss%20You%20Much.dff&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/03 -Miss You Much.dff Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F12%20384K-Never%20Know.wav&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/12 384K-Never Know.wav Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F128%20-%20Overture.dff&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/128 - Overture.dff Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2FDSD64-%20Suite%20No.%201%20(for%20Orchestra)%20Balletto%20detto%20Il%20Conte%20Orlan.dff&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/DSD64- Suite No. 1 (for Orchestra) Balletto detto Il Conte Orlan.dff Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2FTrack04.wav&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/Track04.wav Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2FWechseldominante%20-%20Babel%20(DSD512).dff&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/Wechseldominante - Babel (DSD512).dff Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=%E5%AE%8B%E5%B0%8F%E7%9D%BF//extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F%E5%AE%8B%E5%B0%8F%E7%9D%BF%20-%20%E7%82%B9%E6%AD%8C%E7%9A%84%E4%BA%BA.mp3&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/宋小睿 - 点歌的人.mp3 Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=%C3%85%C3%AD%C2%B7%C2%BC/%C2%B4%C2%BF%C3%89%C2%AB%C2%BD%C3%87%C2%BE%C2%AB%C3%91%C2%A1/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F13.%E8%80%81%E4%BA%BA%E4%B8%8E%E6%B5%B7.mp3&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/13.老人与海.mp3 Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=Johnny%20Ip/AM6023HR/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F16%2096k%20A%20Song%20For%20Me.flac&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/16 96k A Song For Me.flac Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=David%20Craighead/Howard%20Hanson%20An%20American%20Romantic/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F08%20Hanson%20Praise%20Ye%20the%20Lord.dsf&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/08 Hanson Praise Ye the Lord.dsf Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=David%20Craighead/Howard%20Hanson%20An%20American%20Romantic/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F256-%20Hanson%20Praise%20Ye%20the%20Lord.dsf&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/256- Hanson Praise Ye the Lord.dsf Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=Liza%20Ferschtman%2C%20Het%20Gelders%20Orkest%2C%20Kees%20Bakels/Mendelssohn%3A%20Violin%20Concerto%2C%20Op.%2064%20%2F%20String%20Octet%2C%20Op.%2020/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F352.8K-Violin%20Concerto%20in%20E%20minor%2C%20Op.%2064-%20I.%20Allegro%20molto%20appassionato.flac&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/352.8K-Violin Concerto in E minor, Op. 64- I. Allegro molto appassionato.flac Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=London%20Symphony%20Orchestra/The%20Three-Cornered%20Hat%20(Complete%20Ballet)/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F48kThe%20Corregidor.flac&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/48kThe Corregidor.flac Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=%E8%BD%A9%E4%B8%9C/%E7%A2%8E%E9%93%B6%E5%87%A0%E4%B8%A4/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F%E8%BD%A9%E4%B8%9C-%E7%A2%8E%E9%93%B6%E5%87%A0%E4%B8%A4.flac&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/轩东-碎银几两.flac Oct 17 05:55:15 opera volumio[1182]: info: ALBUMART /albumart?cacheid=256&web=%E9%98%BF%E6%9D%9C/%E9%86%87%E6%83%85%E6%AD%8C/extralarge&path=%2Fmnt%2FUSB%2FM2%2FMUSIC%2F%E9%98%BF%E6%9D%9C-%E7%A6%BB%E5%88%AB.flac&metadata=false Oct 17 05:55:15 opera volumio[1182]: info: URI /mnt/USB/M2/MUSIC/阿杜-离别.flac Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::getState Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand status Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::saveQueue Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushQueue Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::updateTrackBlock Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrackBlock Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPlay Oct 17 05:55:15 opera volumio[1182]: verbose: UNSET VOLATILE Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::play index 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::stop Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 05:55:15 opera volumio[1182]: verbose: UNSET VOLATILE Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::play index undefined Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::startPlaybackTimer Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::clearAddPlayTracks USB/M2/MUSIC/Track04.wav Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand status took 10 milliseconds Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand stop took 2 milliseconds Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::parseState Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 05:55:15 opera volumio[1182]: info: Oct 17 05:55:15 opera volumio[1182]: ---------------------------- MPD announces system playlist update Oct 17 05:55:15 opera volumio[1182]: info: Ignoring MPD Status Update Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand clear took 3 milliseconds Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::parseTrackInfo Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand add "USB/M2/MUSIC/Track04.wav" Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::pushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: 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":"Track04.wav","artist":null,"album":null,"uri":"USB/M2/MUSIC/Track04.wav","trackType":"wav"} Oct 17 05:55:15 opera volumio[1182]: verbose: CURRENT POSITION 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::syncState stateService stop Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::syncState currentStatus stop Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: No code Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: ------------------------------ 32ms Oct 17 05:55:15 opera volumio[1182]: error: updateQueue error: null Oct 17 05:55:15 opera volumio[1182]: info: Oct 17 05:55:15 opera volumio[1182]: ---------------------------- MPD announces system playlist update Oct 17 05:55:15 opera volumio[1182]: info: Ignoring MPD Status Update Oct 17 05:55:15 opera volumio[1182]: info: ------------------------------ 23ms Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand add "USB/M2/MUSIC/Track04.wav" took 22 milliseconds Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand play Oct 17 05:55:15 opera volumio[1182]: info: ------------------------------ 11ms Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand play took 11 milliseconds Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: Oct 17 05:55:15 opera volumio[1182]: ---------------------------- MPD announces state update: player Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::getState Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand status Oct 17 05:55:15 opera volumio[1182]: info: Oct 17 05:55:15 opera volumio[1182]: ---------------------------- MPD announces state update: player Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::getState Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand status Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand status took 2 milliseconds Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::parseState Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand status took 4 milliseconds Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::parseState Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::parseTrackInfo Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::pushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track04.wav","artist":null,"album":null,"uri":"USB/M2/MUSIC/Track04.wav","trackType":"wav"} Oct 17 05:55:15 opera volumio[1182]: verbose: CURRENT POSITION 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::syncState stateService play Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::syncState currentStatus stop Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: ------------------------------ 17ms Oct 17 05:55:15 opera volumio[1182]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 17 05:55:15 opera volumio[1182]: verbose: ControllerMpd::parseTrackInfo Oct 17 05:55:15 opera volumio[1182]: info: ControllerMpd::pushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track04.wav","artist":null,"album":null,"uri":"USB/M2/MUSIC/Track04.wav","trackType":"wav"} Oct 17 05:55:15 opera volumio[1182]: verbose: CURRENT POSITION 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::syncState stateService play Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::syncState currentStatus play Oct 17 05:55:15 opera volumio[1182]: info: Received an update from plugin. extracting info from payload Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:55:15 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:55:15 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:55:15 opera volumio[1182]: info: ------------------------------ 31ms Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:15 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:55:52 opera kernel: rtk_btcoex: hci accept connreq, notify wifi page start Oct 17 05:55:52 opera kernel: rtk_btcoex: notify wifi page success end Oct 17 05:55:52 opera kernel: rtk_btcoex: io cap req, notify wifi pair start Oct 17 05:55:53 opera kernel: rtk_btcoex: link key notify, notify wifi pair end Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0040 Oct 17 05:55:53 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0040, result 0x0000 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0040, scid 0x0040 Oct 17 05:55:53 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0040, scid 0x0040, dir 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0041 Oct 17 05:55:53 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0041, result 0x0000 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0040, scid 0x0041 Oct 17 05:55:53 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0040, scid 0x0041, dir 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0042 Oct 17 05:55:53 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0042, result 0x0000 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0040, scid 0x0042 Oct 17 05:55:53 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0040, scid 0x0042, dir 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0019, scid 0x0043 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0043, result 0x0001 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0041 Oct 17 05:55:53 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0002, dcid 0x0044, scid 0x0041, result 0x0000 Oct 17 05:55:53 opera bluetoothd[906]: GAP and GATT are mandatory Oct 17 05:55:53 opera bluetoothd[906]: gap-gatt-profile profile probe failed for E4:0E:EE:64:C7:3B Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0043, result 0x0000 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap connection success, update connection Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x04 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 17 05:55:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0002 Oct 17 05:55:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x04 Oct 17 05:55:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Oct 17 05:55:53 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0019, scid 0x0045 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0042, scid 0x0045, result 0x0001 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:53 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0042, scid 0x0045, result 0x0000 Oct 17 05:55:53 opera kernel: rtk_btcoex: l2cap connection success, update connection Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x04 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 2 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Oct 17 05:55:53 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Oct 17 05:55:54 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:55:55 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:55:55 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 1 Oct 17 05:55:55 opera kernel: rtk_btcoex: TX l2cap disconn req, hndl 0x0002, dcid 0x0044, scid 0x0041 Oct 17 05:55:55 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0044, scid 0x0041, dir 1 Oct 17 05:55:55 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Oct 17 05:55:55 opera kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0002, PSM 0x0017, scid 0x0043 Oct 17 05:55:55 opera kernel: rtk_btcoex: PSM(0x0017) do not need parse Oct 17 05:55:55 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Oct 17 05:55:55 opera kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0002, dcid 0x0046, scid 0x0043, result 0x0000 Oct 17 05:55:55 opera kernel: input: E4:0E:EE:64:C7:3B as /devices/virtual/input/input4 Oct 17 05:55:55 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 17 05:55:55 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 17 05:55:55 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0047 Oct 17 05:55:55 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 17 05:55:55 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0041, scid 0x0047, result 0x0000 Oct 17 05:55:55 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Oct 17 05:55:55 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0041, scid 0x0047 Oct 17 05:55:55 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0041, scid 0x0047, dir 0 Oct 17 05:55:55 opera volumio[1182]: ------------------------------------ BT MESSAGE: BT STATUS: stopped Oct 17 05:55:55 opera volumio[1182]: BT PLUGIN MESSAGE: Playing: false Oct 17 05:55:55 opera volumio[1182]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 17 05:55:55 opera volumio[1182]: BT PLUGIN MESSAGE: STATE:undefined Oct 17 05:55:55 opera volumio[1182]: BT PLUGIN MESSAGE: Duration is 0 Oct 17 05:55:56 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:55:57 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:55:58 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:55:59 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:00 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:00 opera kernel: rtk_btcoex: update_hid_active_state: handle 0x0002, interval 800 Oct 17 05:56:00 opera kernel: rtk_btcoex: HID not connected, nothing to be down Oct 17 05:56:01 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:02 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:03 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:04 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:05 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:06 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:07 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:08 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:09 opera kernel: rtk_btcoex: update_hid_active_state: handle 0x0002, interval 0 Oct 17 05:56:09 opera kernel: rtk_btcoex: HID not connected, nothing to be down Oct 17 05:56:09 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:10 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:11 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:11 opera volumio[1182]: BT PLUGIN MESSAGE: Duration is 60000 Oct 17 05:56:12 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:13 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:14 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:15 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:16 opera kernel: rtk_btcoex: update_hid_active_state: handle 0x0002, interval 800 Oct 17 05:56:16 opera kernel: rtk_btcoex: HID not connected, nothing to be down Oct 17 05:56:16 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:17 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:18 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:19 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:20 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:21 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:22 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:23 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:24 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:25 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:26 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:27 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:28 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:29 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:30 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:31 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:32 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:33 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:34 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:35 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:36 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:37 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:38 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:39 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:40 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:41 opera kernel: rtk_btcoex: update_hid_active_state: handle 0x0002, interval 0 Oct 17 05:56:41 opera kernel: rtk_btcoex: HID not connected, nothing to be down Oct 17 05:56:41 opera volumio[1182]: BT PLUGIN MESSAGE: Duration is 221000 Oct 17 05:56:41 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:42 opera volumio[1182]: ------------------------------------ BT MESSAGE: BT STATUS: playing Oct 17 05:56:42 opera volumio[1182]: BT PLUGIN MESSAGE: Playing: true Oct 17 05:56:42 opera volumio[1182]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 17 05:56:42 opera volumio[1182]: BT PLUGIN MESSAGE: STATE:undefined Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::volumioStop Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::stop Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 05:56:42 opera volumio[1182]: verbose: UNSET VOLATILE Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::stPlaybackTimer Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::updateTrackBlock Oct 17 05:56:42 opera volumio[1182]: info: CorePlayQueue::getTrackBlock Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:42 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::serviceStop Oct 17 05:56:42 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::serviceStop Oct 17 05:56:42 opera volumio[1182]: info: ControllerMpd::stop Oct 17 05:56:42 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 05:56:42 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:42 opera volumio[1182]: info: sendMpdCommand stop took 71 milliseconds Oct 17 05:56:42 opera volumio[1182]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Oct 17 05:56:42 opera volumio[1182]: info: Enabling Bluetooth Audio Output Oct 17 05:56:42 opera volumio[1182]: info: Oct 17 05:56:42 opera volumio[1182]: ---------------------------- MPD announces state update: player Oct 17 05:56:42 opera volumio[1182]: info: ControllerMpd::getState Oct 17 05:56:42 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand status Oct 17 05:56:42 opera volumio[1182]: info: sendMpdCommand status took 4 milliseconds Oct 17 05:56:42 opera volumio[1182]: verbose: ControllerMpd::parseState Oct 17 05:56:42 opera volumio[1182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 05:56:42 opera volumio[1182]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 05:56:42 opera volumio[1182]: verbose: ControllerMpd::parseTrackInfo Oct 17 05:56:42 opera volumio[1182]: info: ControllerMpd::pushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:42 opera volumio[1182]: info: CorePlayQueue::getTrack 4 Oct 17 05:56:42 opera volumio[1182]: 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":"Track04.wav","artist":null,"album":null,"uri":"USB/M2/MUSIC/Track04.wav","trackType":"wav"} Oct 17 05:56:42 opera volumio[1182]: verbose: CURRENT POSITION 4 Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::syncState stateService stop Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::syncState currentStatus stop Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:42 opera volumio[1182]: info: No code Oct 17 05:56:42 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:42 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:42 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:42 opera volumio[1182]: info: ------------------------------ 34ms Oct 17 05:56:42 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:42 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:42 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:42 opera volumio[1182]: info: Bluetooth Audio successfully Enabled Oct 17 05:56:43 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 17 05:56:44 opera volumio[1182]: BT PLUGIN MESSAGE: Duration is 221640 Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:56:44 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:44 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:56:44 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:44 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:44 opera bluetoothd[906]: /org/bluez/hci0/dev_E4_0E_EE_64_C7_3B/fd0: fd(20) ready Oct 17 05:56:44 opera volumio[1182]: ------------------------------------ BT MESSAGE: BT STATUS: pending Oct 17 05:56:44 opera volumio[1182]: ------------------------------------ BT MESSAGE: BT STATUS: active Oct 17 05:56:44 opera volumio[1182]: BT PLUGIN MESSAGE: Playing: true Oct 17 05:56:44 opera volumio[1182]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Oct 17 05:56:44 opera volumio[1182]: BT PLUGIN MESSAGE: STATE:undefined Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::servicePushState Oct 17 05:56:44 opera volumio[1182]: info: CoreStateMachine::pushState Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::volumioPushState Oct 17 05:56:44 opera volumio[1182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 05:56:44 opera volumio[1182]: info: Updating RAAT Signal Path Oct 17 05:56:44 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = 4 Oct 17 05:56:44 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 4 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0002 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x04 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x04 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtp: v 2, cc 0, pt 96 Oct 17 05:56:44 opera kernel: rtk_btcoex: syncword: 9c Oct 17 05:56:44 opera kernel: rtk_btcoex: freq 44.1kHz Oct 17 05:56:44 opera kernel: rtk_btcoex: blocks 16 Oct 17 05:56:44 opera kernel: rtk_btcoex: channel mode JOINT_STEREO Oct 17 05:56:44 opera kernel: rtk_btcoex: allocation method LOUDNESS Oct 17 05:56:44 opera kernel: rtk_btcoex: subbands 8 Oct 17 05:56:44 opera kernel: rtk_btcoex: bitpool 53 Oct 17 05:56:44 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc51 Oct 17 05:56:44 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 21 Oct 17 05:56:44 opera volumio[1182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 05:56:44 opera volumio[1182]: { Error: connect ECONNREFUSED 162.220.12.226:443 Oct 17 05:56:44 opera volumio[1182]: at Object._errnoException (util.js:1022:11) Oct 17 05:56:44 opera volumio[1182]: at _exceptionWithHostPort (util.js:1044:20) Oct 17 05:56:44 opera volumio[1182]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 17 05:56:44 opera volumio[1182]: code: 'ECONNREFUSED', Oct 17 05:56:44 opera volumio[1182]: errno: 'ECONNREFUSED', Oct 17 05:56:44 opera volumio[1182]: syscall: 'connect', Oct 17 05:56:44 opera volumio[1182]: address: '162.220.12.226', Oct 17 05:56:44 opera volumio[1182]: port: 443 } Oct 17 05:56:44 opera volumio[1182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 05:56:45 opera sudo[4097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-17 05:55 Oct 17 05:56:45 opera sudo[4097]: 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="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"