-- Logs begin at Mon 2024-11-25 13:00:36 CET, end at Mon 2024-11-25 13:10:54 CET. -- Nov 25 13:09:06 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:09:06 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:15 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::ClearQueue Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::stPlaybackTimer Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::updateTrackBlock Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::getTrackBlock Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:09:15 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:09:15 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::serviceStop Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::serviceStop Nov 25 13:09:15 volumio volumio[1121]: info: ControllerMpd::stop Nov 25 13:09:15 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::clearPlayQueue Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::saveQueue Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::addQueueItems Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::addQueueItems Nov 25 13:09:15 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:09:15 volumio volumio[1121]: info: Adding Item to queue: webeighties/3 Nov 25 13:09:15 volumio volumio[1121]: info: Exploding uri webeighties/3 in service 80s80s Nov 25 13:09:15 volumio volumio[1121]: info: [1732536555891] [80s80s] explodeUri: webeighties/3 Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::saveQueue Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::updateTrackBlock Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::getTrackBlock Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::volumioPlay Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::play index 0 Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::play index undefined Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:15 volumio volumio[1121]: info: CoreStateMachine::startPlaybackTimer Nov 25 13:09:15 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:09:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 25 13:09:15 volumio volumio[1121]: info: [1732536555954] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Nov 25 13:09:15 volumio volumio[1121]: info: [1732536555955] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Nov 25 13:09:15 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:09:16 volumio volumio[1121]: info: Nov 25 13:09:16 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand stop took 139 milliseconds Nov 25 13:09:16 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music115/v4/4c/a7/06/4ca7066d-0abf-8330-0b9c-766bc35bcff5/source/600x600bb.jpg","name":"John Waite - Missing You","title":"Missing You","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"254","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 25 13:09:16 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::syncState stateService stop Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:09:16 volumio volumio[1121]: info: No code Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:09:16 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:09:16 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:09:16 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand status took 186 milliseconds Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:09:16 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:09:16 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 25 13:09:16 volumio volumio[1121]: info: ------------------------------ 237ms Nov 25 13:09:16 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:09:16 volumio volumio[1121]: info: [1732536556390] [80s80s] received new event containing 2 songs. Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand stop took 2 milliseconds Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand clear Nov 25 13:09:16 volumio volumio[1121]: info: Nov 25 13:09:16 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:09:16 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand clear took 3 milliseconds Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 25 13:09:16 volumio volumio[1121]: error: updateQueue error: null Nov 25 13:09:16 volumio volumio[1121]: info: ------------------------------ 6ms Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand consume 1 took 3 milliseconds Nov 25 13:09:16 volumio volumio[1121]: info: [1732536556405] [80s80s] adding url: http://streams.80s80s.de/party/mp3-192/volumio Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" Nov 25 13:09:16 volumio volumio[1121]: info: Nov 25 13:09:16 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:09:16 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" took 3 milliseconds Nov 25 13:09:16 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Nov 25 13:09:16 volumio volumio[1121]: info: ------------------------------ 21ms Nov 25 13:09:16 volumio volumio[1121]: info: sendMpdCommand play took 4 milliseconds Nov 25 13:09:16 volumio volumio[1121]: info: [1732536556429] [80s80s] Pushing the next song state: Phil Collins - You Can't Hurry Love Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music114/v4/97/70/2a/97702ae0-4f86-f991-c57d-4600c3b07b02/source/600x600bb.jpg","name":"Phil Collins - You Can't Hurry Love","title":"You Can't Hurry Love","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"165","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 25 13:09:16 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:09:16 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Nov 25 13:09:16 volumio volumio[1121]: info: [1732536556435] [80s80s] PlayNextTrack API delay: 30 Nov 25 13:09:16 volumio volumio[1121]: info: [1732536556443] [80s80s] Setting timer to: 165000 milliseconds. Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 1 Nov 25 13:09:16 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:09:16 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:16 volumio volumio[1121]: info: Listing playlists Nov 25 13:09:16 volumio volumio[1121]: info: Listing playlists Nov 25 13:09:17 volumio volumio[1121]: info: Nov 25 13:09:17 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:09:17 volumio volumio[1121]: info: Nov 25 13:09:17 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand status took 5 milliseconds Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:09:17 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:17 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:17 volumio volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 25 13:09:17 volumio volumio[1121]: info: ------------------------------ 18ms Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:09:17 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:17 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:17 volumio volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 25 13:09:17 volumio volumio[1121]: info: ------------------------------ 20ms Nov 25 13:09:17 volumio volumio[1121]: info: Nov 25 13:09:17 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:09:17 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:09:17 volumio volumio[1121]: info: Nov 25 13:09:17 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:09:17 volumio volumio[1121]: info: ------------------------------ 7ms Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:09:17 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:17 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:17 volumio volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 25 13:09:17 volumio volumio[1121]: info: ------------------------------ 21ms Nov 25 13:09:17 volumio volumio[1121]: info: Nov 25 13:09:17 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:09:17 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:09:17 volumio volumio[1121]: info: Nov 25 13:09:17 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:09:17 volumio volumio[1121]: info: ------------------------------ 7ms Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand status took 3 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:09:17 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 13:09:17 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:09:17 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:09:17 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:09:17 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:17 volumio volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 25 13:09:17 volumio volumio[1121]: info: ------------------------------ 14ms Nov 25 13:09:23 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 13:09:33 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:09:33 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:33 volumio volumio[1121]: info: Listing playlists Nov 25 13:09:33 volumio volumio[1121]: info: Listing playlists Nov 25 13:09:43 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:09:43 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:53 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:09:53 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:09:53 volumio volumio[1121]: info: Listing playlists Nov 25 13:09:53 volumio volumio[1121]: info: Listing playlists Nov 25 13:09:56 volumio kernel: rtk_btcoex: hci accept connreq, notify wifi page start Nov 25 13:09:56 volumio kernel: rtk_btcoex: notify wifi page success end Nov 25 13:09:58 volumio kernel: rtk_btcoex: io cap req, notify wifi pair start Nov 25 13:09:59 volumio kernel: rtk_btcoex: link key notify, notify wifi pair end Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0042 Nov 25 13:09:59 volumio kernel: rtk_btcoex: PSM(0x0001) do not need parse Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:09:59 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0042, result 0x0000 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0040, scid 0x0042 Nov 25 13:09:59 volumio kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0040, scid 0x0042, dir 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0043 Nov 25 13:09:59 volumio kernel: rtk_btcoex: PSM(0x0001) do not need parse Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:09:59 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0043, result 0x0000 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0040, scid 0x0043 Nov 25 13:09:59 volumio kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0040, scid 0x0043, dir 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0044 Nov 25 13:09:59 volumio kernel: rtk_btcoex: PSM(0x0001) do not need parse Nov 25 13:09:59 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0044, result 0x0000 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0040, scid 0x0044 Nov 25 13:09:59 volumio kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0040, scid 0x0044, dir 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0019, scid 0x0045 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:09:59 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0045, result 0x0001 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Nov 25 13:09:59 volumio kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0041 Nov 25 13:09:59 volumio kernel: rtk_btcoex: PSM(0x0001) do not need parse Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:09:59 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0045, result 0x0001 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Nov 25 13:09:59 volumio kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0002, dcid 0x0046, scid 0x0041, result 0x0000 Nov 25 13:09:59 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:10:00 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0040, scid 0x0045, result 0x0000 Nov 25 13:10:00 volumio kernel: rtk_btcoex: l2cap connection success, update connection Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x04 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Nov 25 13:10:00 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Nov 25 13:10:00 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0002 Nov 25 13:10:00 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x04 Nov 25 13:10:00 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Nov 25 13:10:00 volumio kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Nov 25 13:10:00 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:10:00 volumio kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0019, scid 0x0047 Nov 25 13:10:00 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0042, scid 0x0047, result 0x0001 Nov 25 13:10:00 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:10:00 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0042, scid 0x0047, result 0x0000 Nov 25 13:10:00 volumio kernel: rtk_btcoex: l2cap connection success, update connection Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x04 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 2 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Nov 25 13:10:00 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Nov 25 13:10:01 volumio volumio[1121]: ------------------------------------ BT MESSAGE: BT STATUS: pending Nov 25 13:10:01 volumio kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = 4 Nov 25 13:10:01 volumio kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 4 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0002 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x04 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x04 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtp: v 2, cc 0, pt 96 Nov 25 13:10:01 volumio kernel: rtk_btcoex: syncword: 9c Nov 25 13:10:01 volumio kernel: rtk_btcoex: freq 44.1kHz Nov 25 13:10:01 volumio kernel: rtk_btcoex: blocks 16 Nov 25 13:10:01 volumio kernel: rtk_btcoex: channel mode JOINT_STEREO Nov 25 13:10:01 volumio kernel: rtk_btcoex: allocation method LOUDNESS Nov 25 13:10:01 volumio kernel: rtk_btcoex: subbands 8 Nov 25 13:10:01 volumio kernel: rtk_btcoex: bitpool 53 Nov 25 13:10:01 volumio kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc51 Nov 25 13:10:01 volumio kernel: rtk_btcoex: l2cap op 6, len 16, out 1 Nov 25 13:10:01 volumio kernel: rtk_btcoex: TX l2cap disconn req, hndl 0x0002, dcid 0x0046, scid 0x0041 Nov 25 13:10:01 volumio kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0046, scid 0x0041, dir 1 Nov 25 13:10:01 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 35 Nov 25 13:10:02 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Nov 25 13:10:02 volumio kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0002, PSM 0x0017, scid 0x0041 Nov 25 13:10:02 volumio kernel: rtk_btcoex: PSM(0x0017) do not need parse Nov 25 13:10:02 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Nov 25 13:10:02 volumio kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0002, dcid 0x0048, scid 0x0041, result 0x0000 Nov 25 13:10:02 volumio kernel: input: CC:9E:A2:34:E7:DC as /devices/virtual/input/input3 Nov 25 13:10:02 volumio kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Nov 25 13:10:02 volumio kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0002, PSM 0x0001, scid 0x0049 Nov 25 13:10:02 volumio kernel: rtk_btcoex: PSM(0x0001) do not need parse Nov 25 13:10:02 volumio kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Nov 25 13:10:02 volumio kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0002, dcid 0x0043, scid 0x0049, result 0x0000 Nov 25 13:10:02 volumio kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Nov 25 13:10:02 volumio kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0002, dcid 0x0043, scid 0x0049 Nov 25 13:10:02 volumio kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0002, dcid 0x0043, scid 0x0049, dir 0 Nov 25 13:10:02 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 72 Nov 25 13:10:03 volumio systemd-udevd[3878]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 25 13:10:03 volumio systemd-logind[568]: Watching system buttons on /dev/input/event3 (CC:9E:A2:34:E7:DC) Nov 25 13:10:03 volumio bluetoothd[615]: /org/bluez/hci0/dev_CC_9E_A2_34_E7_DC/fd0: fd(21) ready Nov 25 13:10:03 volumio volumio[1121]: ------------------------------------ BT MESSAGE: BT STATUS: active Nov 25 13:10:03 volumio volumio[1121]: BT PLUGIN MESSAGE: Playing: true Nov 25 13:10:03 volumio volumio[1121]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 25 13:10:03 volumio volumio[1121]: BT PLUGIN MESSAGE: STATE:undefined Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioStop Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::stPlaybackTimer Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::updateTrackBlock Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrackBlock Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:03 volumio pulseaudio[2401]: Support for resampler 'src-sinc-best-quality' not compiled in, reverting to 'auto'. Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::serviceStop Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::serviceStop Nov 25 13:10:03 volumio volumio[1121]: info: ControllerMpd::stop Nov 25 13:10:03 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 13:10:03 volumio pulseaudio[2401]: Cannot set requested source latency of 66.67 ms, adjusting to 135.29 ms Nov 25 13:10:03 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:03 volumio volumio[1121]: info: Nov 25 13:10:03 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:03 volumio volumio[1121]: info: sendMpdCommand stop took 32 milliseconds Nov 25 13:10:03 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:03 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music114/v4/97/70/2a/97702ae0-4f86-f991-c57d-4600c3b07b02/source/600x600bb.jpg","name":"Phil Collins - You Can't Hurry Love","title":"You Can't Hurry Love","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"165","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 25 13:10:03 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::syncState stateService stop Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:03 volumio volumio[1121]: info: No code Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:03 volumio volumio[1121]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Nov 25 13:10:03 volumio volumio[1121]: info: Enabling Bluetooth Audio Output Nov 25 13:10:03 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:03 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:03 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:03 volumio volumio[1121]: info: sendMpdCommand status took 104 milliseconds Nov 25 13:10:03 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:03 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:03 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 13:10:03 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:03 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:03 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:03 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:03 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:03 volumio volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 25 13:10:03 volumio volumio[1121]: info: ------------------------------ 152ms Nov 25 13:10:03 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:03 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:03 volumio pulseaudio[2401]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Nov 25 13:10:03 volumio pulseaudio[2401]: Cannot set requested sink latency of 337.51 ms, adjusting to 600.00 ms Nov 25 13:10:03 volumio volumio[1121]: info: Bluetooth Audio successfully Enabled Nov 25 13:10:03 volumio pulseaudio[2401]: Too many underruns, increasing latency to 815.32 ms Nov 25 13:10:03 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:04 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 66 Nov 25 13:10:05 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:06 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Nov 25 13:10:07 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:08 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 63 Nov 25 13:10:09 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 68 Nov 25 13:10:10 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:11 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 76 Nov 25 13:10:12 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Nov 25 13:10:12 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz.mp4 Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth/motherearth Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/04-Pueblo Nuevo.flac Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/03-El Cuarto de Tula.flac Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/02-De Camino a La Vereda.flac Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/01-Chan Chan.flac Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: mnt/USB/UNTITLED/Zene HDTracks/Ben E. King/Spanish Harlem/01-Amor.flac Nov 25 13:10:12 volumio volumio[1121]: info: Preloading song: music-library/USB/UNTITLED/Zene HDTracks/Bebo Best & The Super Lounge Orchestra/Saronno on The Rocks/13 - Sing Sing Sing (Koko Chanel Remix).flac Nov 25 13:10:12 volumio volumio[1121]: info: Exploding uri https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz.mp4 in service motherearthradio Nov 25 13:10:12 volumio volumio[1121]: info: Exploding uri https://motherearth.streamserver24.com/listen/motherearth/motherearth in service motherearthradio Nov 25 13:10:13 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:13 volumio volumio[1121]: info: Exploding uri mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/04-Pueblo Nuevo.flac in service mpd Nov 25 13:10:13 volumio volumio[1121]: info: ALBUMART /albumart?cacheid=337&web=Buena%20Vista%20Social%20Club/Buena%20Vista%20Social%20Club/extralarge&path=%2Fmnt%2FUSB%2FUNTITLED%2FZene%20HDTracks%2FBuena%20Vista%20Social%20Club%2FBuena%20Vista%20Social%20Club%2F04-Pueblo%20Nuevo.flac&metadata=false Nov 25 13:10:13 volumio volumio[1121]: info: URI /mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/04-Pueblo Nuevo.flac Nov 25 13:10:13 volumio volumio[1121]: info: Exploding uri mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/03-El Cuarto de Tula.flac in service mpd Nov 25 13:10:13 volumio volumio[1121]: info: ALBUMART /albumart?cacheid=337&web=Buena%20Vista%20Social%20Club/Buena%20Vista%20Social%20Club/extralarge&path=%2Fmnt%2FUSB%2FUNTITLED%2FZene%20HDTracks%2FBuena%20Vista%20Social%20Club%2FBuena%20Vista%20Social%20Club%2F03-El%20Cuarto%20de%20Tula.flac&metadata=false Nov 25 13:10:13 volumio volumio[1121]: info: URI /mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/03-El Cuarto de Tula.flac Nov 25 13:10:13 volumio volumio[1121]: info: Exploding uri mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/02-De Camino a La Vereda.flac in service mpd Nov 25 13:10:13 volumio volumio[1121]: info: ALBUMART /albumart?cacheid=337&web=Buena%20Vista%20Social%20Club/Buena%20Vista%20Social%20Club/extralarge&path=%2Fmnt%2FUSB%2FUNTITLED%2FZene%20HDTracks%2FBuena%20Vista%20Social%20Club%2FBuena%20Vista%20Social%20Club%2F02-De%20Camino%20a%20La%20Vereda.flac&metadata=false Nov 25 13:10:13 volumio volumio[1121]: info: URI /mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/02-De Camino a La Vereda.flac Nov 25 13:10:13 volumio volumio[1121]: info: Exploding uri mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/01-Chan Chan.flac in service mpd Nov 25 13:10:13 volumio volumio[1121]: info: ALBUMART /albumart?cacheid=337&web=Buena%20Vista%20Social%20Club/Buena%20Vista%20Social%20Club/extralarge&path=%2Fmnt%2FUSB%2FUNTITLED%2FZene%20HDTracks%2FBuena%20Vista%20Social%20Club%2FBuena%20Vista%20Social%20Club%2F01-Chan%20Chan.flac&metadata=false Nov 25 13:10:13 volumio volumio[1121]: info: URI /mnt/USB/UNTITLED/Zene HDTracks/Buena Vista Social Club/Buena Vista Social Club/01-Chan Chan.flac Nov 25 13:10:13 volumio volumio[1121]: info: Exploding uri mnt/USB/UNTITLED/Zene HDTracks/Ben E. King/Spanish Harlem/01-Amor.flac in service mpd Nov 25 13:10:13 volumio volumio[1121]: info: ALBUMART /albumart?cacheid=337&web=Ben%20E.%20King/Spanish%20Harlem/extralarge&path=%2Fmnt%2FUSB%2FUNTITLED%2FZene%20HDTracks%2FBen%20E.%20King%2FSpanish%20Harlem%2F01-Amor.flac&metadata=false Nov 25 13:10:13 volumio volumio[1121]: info: URI /mnt/USB/UNTITLED/Zene HDTracks/Ben E. King/Spanish Harlem/01-Amor.flac Nov 25 13:10:13 volumio volumio[1121]: info: Exploding uri music-library/USB/UNTITLED/Zene HDTracks/Bebo Best & The Super Lounge Orchestra/Saronno on The Rocks/13 - Sing Sing Sing (Koko Chanel Remix).flac in service mpd Nov 25 13:10:13 volumio volumio[1121]: info: ALBUMART /albumart?cacheid=337&web=Bebo%20Best/Saronno%20on%20The%20Rocks/extralarge&path=%2Fmnt%2FUSB%2FUNTITLED%2FZene%20HDTracks%2FBebo%20Best%20%26%20The%20Super%20Lounge%20Orchestra%2FSaronno%20on%20The%20Rocks%2F13%20-%20Sing%20Sing%20Sing%20(Koko%20Chanel%20Remix).flac&metadata=false Nov 25 13:10:13 volumio volumio[1121]: info: URI /mnt/USB/UNTITLED/Zene HDTracks/Bebo Best & The Super Lounge Orchestra/Saronno on The Rocks/13 - Sing Sing Sing (Koko Chanel Remix).flac Nov 25 13:10:13 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:13 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:13 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:13 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Nov 25 13:10:14 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 25 13:10:14 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:14 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 64 Nov 25 13:10:15 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 73 Nov 25 13:10:16 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 71 Nov 25 13:10:17 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 25 13:10:17 volumio volumio[1121]: info: Getting webradio selection Nov 25 13:10:17 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:17 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Nov 25 13:10:18 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::ClearQueue Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::serviceStop Nov 25 13:10:18 volumio volumio[1121]: info: Received STOP, but no service to execute it Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::clearPlayQueue Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::saveQueue Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::addQueueItems Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::addQueueItems Nov 25 13:10:18 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:18 volumio volumio[1121]: info: Adding Item to queue: https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3 Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::saveQueue Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::updateTrackBlock Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::getTrackBlock Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::volumioPlay Nov 25 13:10:18 volumio volumio[1121]: verbose: UNSET VOLATILE: Service: undefined Nov 25 13:10:18 volumio volumio[1121]: info: Bluetooth Detach Command Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::play index 0 Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::play index undefined Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:18 volumio volumio[1121]: info: CoreStateMachine::startPlaybackTimer Nov 25 13:10:18 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:10:18 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 25 13:10:18 volumio volumio[1121]: info: [1732536618606] ControllerWebradio::clearAddPlayTrack Nov 25 13:10:18 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 13:10:18 volumio volumio[1121]: info: sendMpdCommand stop took 5 milliseconds Nov 25 13:10:18 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand clear Nov 25 13:10:18 volumio volumio[1121]: info: Nov 25 13:10:18 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:18 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:18 volumio volumio[1121]: info: sendMpdCommand clear took 2 milliseconds Nov 25 13:10:18 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand load "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Nov 25 13:10:18 volumio volumio[1121]: error: updateQueue error: null Nov 25 13:10:18 volumio volumio[1121]: info: ------------------------------ 3ms Nov 25 13:10:18 volumio volumio[1121]: info: Bluetooth Audio successfully Disabled Nov 25 13:10:18 volumio volumio[1121]: info: Bluetooth Successfully Detached Nov 25 13:10:18 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 66 Nov 25 13:10:19 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 73 Nov 25 13:10:20 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:21 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Nov 25 13:10:21 volumio volumio[1121]: info: Nov 25 13:10:21 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:21 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:21 volumio volumio[1121]: info: sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" took 2 milliseconds Nov 25 13:10:21 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 25 13:10:21 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Nov 25 13:10:21 volumio volumio[1121]: info: ------------------------------ 3ms Nov 25 13:10:21 volumio volumio[1121]: info: sendMpdCommand play took 1 milliseconds Nov 25 13:10:21 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 63 Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand status took 3 milliseconds Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand status took 2 milliseconds Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:22 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:22 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Nov 25 13:10:22 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 10ms Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:22 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:22 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Nov 25 13:10:22 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:22 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 55ms Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:22 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:22 volumio volumio[1121]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Absolut Radio Nov 25 13:10:22 volumio volumio[1121]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Absolut Radio Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:22 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 27ms Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand status took 26 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 4ms Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand status took 3 milliseconds Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:22 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:22 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1072,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Nov 25 13:10:22 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:22 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 65ms Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:22 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:22 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Nov 25 13:10:22 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:22 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 78ms Nov 25 13:10:22 volumio volumio[1121]: info: [LastFM] Split composite title into artist: PASCAL LETOUBLON and title: FRIENDSHIPS Nov 25 13:10:22 volumio volumio[1121]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:22 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:22 volumio volumio[1121]: info: Nov 25 13:10:22 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 4ms Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand status took 3 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:22 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 13:10:22 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:22 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:22 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:22 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1484,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Nov 25 13:10:22 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:22 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:22 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:22 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:22 volumio volumio[1121]: info: ------------------------------ 44ms Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:22 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 73 Nov 25 13:10:23 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:23 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:24 volumio volumio[1121]: info: Nov 25 13:10:24 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:24 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:24 volumio volumio[1121]: info: Nov 25 13:10:24 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:24 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:24 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:24 volumio volumio[1121]: info: ------------------------------ 2ms Nov 25 13:10:24 volumio volumio[1121]: info: sendMpdCommand status took 2 milliseconds Nov 25 13:10:24 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:24 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:24 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 25 13:10:24 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:24 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:24 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:24 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:24 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Nov 25 13:10:24 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:24 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:24 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:24 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:24 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:24 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:24 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:24 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:24 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:24 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:24 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:24 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:24 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:24 volumio volumio[1121]: info: ------------------------------ 41ms Nov 25 13:10:24 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:24 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:24 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 66 Nov 25 13:10:25 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 72 Nov 25 13:10:26 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 64 Nov 25 13:10:27 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 68 Nov 25 13:10:28 volumio pulseaudio[2401]: Too many underruns, increasing latency to 205.00 ms Nov 25 13:10:28 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 75 Nov 25 13:10:29 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 60 Nov 25 13:10:30 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 76 Nov 25 13:10:31 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 65 Nov 25 13:10:32 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 75 Nov 25 13:10:33 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:33 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:33 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:33 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 71 Nov 25 13:10:34 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 64 Nov 25 13:10:35 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:36 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 71 Nov 25 13:10:37 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::ClearQueue Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::stPlaybackTimer Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::updateTrackBlock Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::getTrackBlock Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:37 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:37 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::serviceStop Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::serviceStop Nov 25 13:10:37 volumio volumio[1121]: info: [1732536637212] ControllerWebradio::stop Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::clearPlayQueue Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::saveQueue Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::addQueueItems Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::addQueueItems Nov 25 13:10:37 volumio volumio[1121]: info: Preload queue cleared Nov 25 13:10:37 volumio volumio[1121]: info: Adding Item to queue: https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3 Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::volumioPushQueue Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::saveQueue Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::updateTrackBlock Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::getTrackBlock Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::volumioPlay Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::play index 0 Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::stop Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::play index undefined Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:37 volumio volumio[1121]: info: CoreStateMachine::startPlaybackTimer Nov 25 13:10:37 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:10:37 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 25 13:10:37 volumio volumio[1121]: info: [1732536637241] ControllerWebradio::clearAddPlayTrack Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Nov 25 13:10:37 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:37 volumio volumio[1121]: info: Nov 25 13:10:37 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:37 volumio volumio[1121]: info: sendMpdCommand stop took 42 milliseconds Nov 25 13:10:37 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:37 volumio volumio[1121]: info: sendMpdCommand stop took 14 milliseconds Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand clear Nov 25 13:10:37 volumio volumio[1121]: info: Nov 25 13:10:37 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:37 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:37 volumio volumio[1121]: info: sendMpdCommand status took 2 milliseconds Nov 25 13:10:37 volumio volumio[1121]: info: sendMpdCommand clear took 2 milliseconds Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Nov 25 13:10:37 volumio volumio[1121]: error: updateQueue error: null Nov 25 13:10:37 volumio volumio[1121]: info: ------------------------------ 4ms Nov 25 13:10:37 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 25 13:10:37 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:37 volumio volumio[1121]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 25 13:10:37 volumio volumio[1121]: info: ------------------------------ 9ms Nov 25 13:10:37 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:38 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:39 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" took 1 milliseconds Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 2ms Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand play took 2 milliseconds Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand status took 2 milliseconds Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand status took 2 milliseconds Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:39 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:39 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Nov 25 13:10:39 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 8ms Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:39 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:39 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Nov 25 13:10:39 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:39 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 51ms Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:39 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:39 volumio volumio[1121]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Absolut Relax Nov 25 13:10:39 volumio volumio[1121]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Absolut Relax Nov 25 13:10:39 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:39 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:39 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:39 volumio volumio[1121]: info: Nov 25 13:10:39 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 24ms Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand status took 23 milliseconds Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 5ms Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand status took 4 milliseconds Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:39 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:39 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1072,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BRYAN ADAMS - LET'S MAKE A NIGHT TO REMEMBER","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Nov 25 13:10:39 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:39 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 63ms Nov 25 13:10:39 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 25 13:10:39 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:39 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:39 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:39 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BRYAN ADAMS - LET'S MAKE A NIGHT TO REMEMBER","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Nov 25 13:10:39 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:39 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:39 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:39 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:39 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:39 volumio volumio[1121]: info: ------------------------------ 66ms Nov 25 13:10:39 volumio volumio[1121]: info: [LastFM] Split composite title into artist: BRYAN ADAMS and title: LET'S MAKE A NIGHT TO REMEMBER Nov 25 13:10:39 volumio volumio[1121]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Nov 25 13:10:39 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:39 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:39 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:39 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:39 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 65 Nov 25 13:10:40 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 72 Nov 25 13:10:41 volumio volumio[1121]: info: Nov 25 13:10:41 volumio volumio[1121]: ---------------------------- MPD announces system playlist update Nov 25 13:10:41 volumio volumio[1121]: info: Ignoring MPD Status Update Nov 25 13:10:41 volumio volumio[1121]: info: Nov 25 13:10:41 volumio volumio[1121]: ---------------------------- MPD announces state update: player Nov 25 13:10:41 volumio volumio[1121]: info: ControllerMpd::getState Nov 25 13:10:41 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Nov 25 13:10:41 volumio volumio[1121]: info: ------------------------------ 2ms Nov 25 13:10:41 volumio volumio[1121]: info: sendMpdCommand status took 1 milliseconds Nov 25 13:10:41 volumio volumio[1121]: verbose: ControllerMpd::parseState Nov 25 13:10:41 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 13:10:41 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 25 13:10:41 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Nov 25 13:10:41 volumio volumio[1121]: info: ControllerMpd::pushState Nov 25 13:10:41 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Nov 25 13:10:41 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Nov 25 13:10:41 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BRYAN ADAMS - LET'S MAKE A NIGHT TO REMEMBER","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Nov 25 13:10:41 volumio volumio[1121]: verbose: CURRENT POSITION 0 Nov 25 13:10:41 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Nov 25 13:10:41 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus play Nov 25 13:10:41 volumio volumio[1121]: info: Received an update from plugin. extracting info from payload Nov 25 13:10:41 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:41 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 13:10:41 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:41 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:41 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:41 volumio volumio[1121]: info: CoreStateMachine::pushState Nov 25 13:10:41 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Nov 25 13:10:41 volumio volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Nov 25 13:10:41 volumio volumio[1121]: info: MRS: Pushing multiroomSync output Nov 25 13:10:41 volumio volumio[1121]: info: ------------------------------ 32ms Nov 25 13:10:41 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:41 volumio volumio[1121]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 25 13:10:41 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:42 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 66 Nov 25 13:10:43 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:43 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:44 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Nov 25 13:10:45 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Nov 25 13:10:46 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 71 Nov 25 13:10:47 volumio ntpd[1331]: 62.168.94.161 local addr 192.168.1.104 -> Nov 25 13:10:47 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Nov 25 13:10:48 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Nov 25 13:10:49 volumio volumio[1121]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.227 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 25 13:10:49 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:49 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 13:10:49 volumio volumio[1121]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 25 13:10:49 volumio volumio[1121]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 13:10:49 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 13:10:49 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:49 volumio volumio[1121]: info: CoreCommandRouter::volumioGetQueue Nov 25 13:10:49 volumio volumio[1121]: info: CoreStateMachine::getQueue Nov 25 13:10:49 volumio volumio[1121]: info: CorePlayQueue::getQueue Nov 25 13:10:49 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 72 Nov 25 13:10:50 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 66 Nov 25 13:10:51 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 64 Nov 25 13:10:52 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 72 Nov 25 13:10:53 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Nov 25 13:10:53 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:53 volumio volumio[1121]: info: Listing playlists Nov 25 13:10:53 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Nov 25 13:10:53 volumio volumio[1121]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Nov 25 13:10:53 volumio volumio[1121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 13:10:53 volumio volumio[1121]: TypeError: Cannot read property 'then' of undefined Nov 25 13:10:53 volumio volumio[1121]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Nov 25 13:10:53 volumio volumio[1121]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2274:30) Nov 25 13:10:53 volumio volumio[1121]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1450:26) Nov 25 13:10:53 volumio volumio[1121]: at Socket.emit (events.js:400:28) Nov 25 13:10:53 volumio volumio[1121]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 13:10:53 volumio volumio[1121]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 25 13:10:53 volumio volumio[1121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 13:10:53 volumio kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 73 Nov 25 13:10:54 volumio sudo[4041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 13:09 Nov 25 13:10:54 volumio sudo[4041]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"