Dec 28 23:16:00 volumio3 volumio[1156]: info: Dec 28 23:16:00 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:00 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:00 volumio3 volumio[1156]: info: Dec 28 23:16:00 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:00 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:00 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:00 volumio3 volumio[1156]: info: ------------------------------ 1ms Dec 28 23:16:00 volumio3 volumio[1156]: info: sendMpdCommand status took 2 milliseconds Dec 28 23:16:00 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:00 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:00 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:00 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:00 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:00 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:00 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:00 volumio3 volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 28 23:16:00 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:02 volumio3 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 23:16:02 volumio3 volumio[1156]: info: CoreCommandRouter::Close All Modals sent Dec 28 23:16:11 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:11 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:11 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:11 volumio3 volumio[1156]: info: Adding Item to queue: webrp/7 Dec 28 23:16:11 volumio3 volumio[1156]: info: Exploding uri webrp/7 in service radio_paradise Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:11 volumio3 volumio[1156]: info: Dec 28 23:16:11 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand stop took 18 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand stop took 9 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:11 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:11 volumio3 volumio[1156]: info: No code Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:11 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:11 volumio3 volumio[1156]: info: Dec 28 23:16:11 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:11 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand status took 16 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand clear took 9 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:11 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:11 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:11 volumio3 volumio[1156]: info: Dec 28 23:16:11 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:11 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:11 volumio3 volumio[1156]: info: ------------------------------ 12ms Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" took 11 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:11 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:11 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:11 volumio3 volumio[1156]: info: ------------------------------ 33ms Dec 28 23:16:11 volumio3 volumio[1156]: info: ------------------------------ 7ms Dec 28 23:16:11 volumio3 volumio[1156]: info: sendMpdCommand play took 2 milliseconds Dec 28 23:16:11 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:12 volumio3 volumio[1156]: info: Dec 28 23:16:12 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:12 volumio3 volumio[1156]: info: Dec 28 23:16:12 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand status took 8 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:12 volumio3 volumio[1156]: 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-192","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:12 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:12 volumio3 volumio[1156]: info: ------------------------------ 11ms Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:12 volumio3 volumio[1156]: 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-192","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:12 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:12 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:12 volumio3 volumio[1156]: info: ------------------------------ 12ms Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:12 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:12 volumio3 volumio[1156]: info: Dec 28 23:16:12 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:12 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:12 volumio3 volumio[1156]: info: Dec 28 23:16:12 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:12 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:12 volumio3 volumio[1156]: info: Dec 28 23:16:12 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:12 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:12 volumio3 volumio[1156]: info: Dec 28 23:16:12 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:12 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:12 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:12 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:12 volumio3 volumio[1156]: info: ------------------------------ 11ms Dec 28 23:16:12 volumio3 volumio[1156]: info: ------------------------------ 7ms Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand status took 7 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:12 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 28 23:16:12 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:12 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:12 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:12 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:12 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:12 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:12 volumio3 volumio[1156]: info: ------------------------------ 20ms Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:12 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:16 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:16 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:16 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:16 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:16 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:16 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:16 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:16 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:16 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:16 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:16 volumio3 volumio[1156]: info: Adding Item to queue: webrp/8 Dec 28 23:16:16 volumio3 volumio[1156]: info: Exploding uri webrp/8 in service radio_paradise Dec 28 23:16:16 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand stop took 14 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand stop took 7 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:17 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: info: No code Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:17 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand status took 13 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand clear took 10 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" Dec 28 23:16:17 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:17 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" took 1 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:17 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 17ms Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 4ms Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand play took 2 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand status took 7 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: 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-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:17 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 12ms Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: 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-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:17 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:17 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 11ms Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:17 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:17 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:17 volumio3 volumio[1156]: info: Dec 28 23:16:17 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:17 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 28 23:16:17 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:17 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:17 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:17 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:17 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:17 volumio3 volumio[1156]: info: ------------------------------ 8ms Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:17 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:18 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:18 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:18 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:18 volumio3 volumio[1156]: info: Adding Item to queue: webrp/8 Dec 28 23:16:18 volumio3 volumio[1156]: info: Using cached record of: webrp/8 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:18 volumio3 volumio[1156]: info: Dec 28 23:16:18 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand stop took 11 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand stop took 5 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:18 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:18 volumio3 volumio[1156]: info: No code Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:18 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:18 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:18 volumio3 volumio[1156]: info: Dec 28 23:16:18 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:18 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand status took 14 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand clear took 10 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" Dec 28 23:16:18 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:18 volumio3 volumio[1156]: info: Dec 28 23:16:18 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:18 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:18 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-128" took 1 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:18 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:18 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:18 volumio3 volumio[1156]: info: ------------------------------ 19ms Dec 28 23:16:18 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:18 volumio3 volumio[1156]: info: sendMpdCommand play took 2 milliseconds Dec 28 23:16:18 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:19 volumio3 volumio[1156]: info: Dec 28 23:16:19 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:19 volumio3 volumio[1156]: info: Dec 28 23:16:19 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand status took 5 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:19 volumio3 volumio[1156]: 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-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:19 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:19 volumio3 volumio[1156]: info: ------------------------------ 8ms Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:19 volumio3 volumio[1156]: 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-128","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:19 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:19 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:19 volumio3 volumio[1156]: info: ------------------------------ 9ms Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:19 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:19 volumio3 volumio[1156]: info: Dec 28 23:16:19 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:19 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:19 volumio3 volumio[1156]: info: Dec 28 23:16:19 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:19 volumio3 volumio[1156]: info: Dec 28 23:16:19 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:19 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:19 volumio3 volumio[1156]: info: Dec 28 23:16:19 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:19 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand status took 2 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:19 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand status took 3 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:19 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:19 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:19 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:19 volumio3 volumio[1156]: info: ------------------------------ 11ms Dec 28 23:16:19 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 23:16:19 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:19 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:19 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (128k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-128","trackType":""} Dec 28 23:16:19 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:19 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:19 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:19 volumio3 volumio[1156]: info: ------------------------------ 16ms Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:19 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:26 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:26 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:26 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:26 volumio3 volumio[1156]: info: Adding Item to queue: webrp/7 Dec 28 23:16:26 volumio3 volumio[1156]: info: Using cached record of: webrp/7 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:26 volumio3 volumio[1156]: info: Dec 28 23:16:26 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand stop took 11 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand stop took 5 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:26 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:26 volumio3 volumio[1156]: info: No code Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:26 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:26 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:26 volumio3 volumio[1156]: info: Dec 28 23:16:26 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:26 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand status took 16 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand clear took 11 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" Dec 28 23:16:26 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:26 volumio3 volumio[1156]: info: Dec 28 23:16:26 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:26 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:26 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" took 2 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:26 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:26 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:26 volumio3 volumio[1156]: info: ------------------------------ 20ms Dec 28 23:16:26 volumio3 volumio[1156]: info: ------------------------------ 4ms Dec 28 23:16:26 volumio3 volumio[1156]: info: sendMpdCommand play took 2 milliseconds Dec 28 23:16:26 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:27 volumio3 volumio[1156]: info: Dec 28 23:16:27 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:27 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:27 volumio3 volumio[1156]: info: Dec 28 23:16:27 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:27 volumio3 volumio[1156]: info: sendMpdCommand status took 6 milliseconds Dec 28 23:16:27 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:27 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:27 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:27 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:27 volumio3 volumio[1156]: 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-192","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:27 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:27 volumio3 volumio[1156]: info: ------------------------------ 9ms Dec 28 23:16:27 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:27 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:27 volumio3 volumio[1156]: 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-192","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:27 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:27 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:27 volumio3 volumio[1156]: info: ------------------------------ 10ms Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:27 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:27 volumio3 volumio[1156]: info: Dec 28 23:16:27 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:27 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:27 volumio3 volumio[1156]: info: Dec 28 23:16:27 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:27 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:27 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:27 volumio3 volumio[1156]: info: sendMpdCommand status took 2 milliseconds Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:27 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:27 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:27 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:27 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:27 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:27 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:27 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:27 volumio3 volumio[1156]: info: ------------------------------ 9ms Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:27 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:30 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:30 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:30 volumio3 volumio[1156]: info: Adding Item to queue: webrp/6 Dec 28 23:16:30 volumio3 volumio[1156]: info: Exploding uri webrp/6 in service radio_paradise Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand stop took 13 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand stop took 5 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:30 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: No code Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:30 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand status took 13 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand clear took 9 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-64" Dec 28 23:16:30 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:30 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-64" took 2 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:30 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 17ms Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 6ms Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand play took 4 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand status took 12 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac-64","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Dec 28 23:16:30 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 17ms Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac-64","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Dec 28 23:16:30 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:30 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 17ms Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:30 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:30 volumio3 volumio[1156]: info: Dec 28 23:16:30 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:30 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 10ms Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand status took 9 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:30 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 28 23:16:30 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:30 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (64k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-64","trackType":"com/aac-64"} Dec 28 23:16:30 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:30 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:30 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:30 volumio3 volumio[1156]: info: ------------------------------ 21ms Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:30 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:34 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:34 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:34 volumio3 volumio[1156]: info: Adding Item to queue: webrp/7 Dec 28 23:16:34 volumio3 volumio[1156]: info: Using cached record of: webrp/7 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand stop took 13 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand stop took 7 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:34 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: No code Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:34 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand status took 12 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand clear took 8 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" Dec 28 23:16:34 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:34 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" took 1 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:34 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 17ms Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 4ms Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand play took 2 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand status took 7 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand status took 3 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: 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-192","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:34 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 12ms Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: 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-192","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:34 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:34 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 11ms Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:34 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:34 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:34 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:34 volumio3 volumio[1156]: info: Dec 28 23:16:34 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand status took 2 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:34 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:34 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:34 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:34 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":""} Dec 28 23:16:34 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:34 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:34 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 15ms Dec 28 23:16:34 volumio3 volumio[1156]: info: ------------------------------ 15ms Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:34 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:39 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:39 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:39 volumio3 volumio[1156]: info: Adding Item to queue: webrp/4 Dec 28 23:16:39 volumio3 volumio[1156]: info: Exploding uri webrp/4 in service radio_paradise Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand stop took 12 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand stop took 6 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:39 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: No code Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:39 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand status took 13 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand clear took 9 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" Dec 28 23:16:39 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:39 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-320" took 2 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:39 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 19ms Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand play took 1 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand status took 5 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand status took 3 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac-320","artist":null,"album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 28 23:16:39 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 11ms Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:39 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:39 volumio3 volumio[1156]: info: Dec 28 23:16:39 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 28 23:16:39 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:39 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 14ms Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 9ms Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand status took 8 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:39 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:39 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 28 23:16:39 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:39 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"319 Kbps","isStreaming":false,"title":"Vundabar - Life Is a Movie","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Dec 28 23:16:39 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:39 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:39 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:39 volumio3 volumio[1156]: info: ------------------------------ 23ms Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:39 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:47 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::ClearQueue Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::serviceStop Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::serviceStop Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::stop Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::clearPlayQueue Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::addQueueItems Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::addQueueItems Dec 28 23:16:47 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:16:47 volumio3 volumio[1156]: info: Adding Item to queue: webrp/9 Dec 28 23:16:47 volumio3 volumio[1156]: info: Exploding uri webrp/9 in service radio_paradise Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::saveQueue Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::updateTrackBlock Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrackBlock Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPlay Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::play index 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::stop Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::play index undefined Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: [RadioParadise] Matched station: Radio Paradise Radio 2050 (FLAC), chan=9 Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand stop took 13 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand stop took 6 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10320.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"Almost Home","title":"Almost Home","artist":"Moby","album":"Reprise","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:47 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService stop Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:47 volumio3 volumio[1156]: info: No code Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Volumio is not playing Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - Clipped samples monitor stopped Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:47 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand status took 15 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand clear took 12 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 28 23:16:47 volumio3 volumio[1156]: error: updateQueue error: null Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: [1766963807739] [RadioParadise] set to consume mode, adding url: http://stream.radioparadise.com/radio2050-flacm Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/radio2050-flacm" Dec 28 23:16:47 volumio3 volumio[1156]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 18ms Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:47 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand add "http://stream.radioparadise.com/radio2050-flacm" took 2 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 2ms Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand play took 2 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=9 Dec 28 23:16:47 volumio3 volumio[1156]: info: [1766963807745] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=9 Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 28 23:16:47 volumio3 volumio[1156]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand status took 6 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 9ms Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 6ms Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:47 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:47 volumio3 volumio[1156]: info: Dec 28 23:16:47 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand status took 2 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:47 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:47 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:47 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:47 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:47 volumio3 volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 28 23:16:47 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:48 volumio3 volumio[1156]: info: [1766963808260] [RadioParadise] received new metadata: {"time":5,"artist":"","title":null,"album":"The Universe Smiles Upon You ii","year":null,"cover":"https://img.radioparadise.com/covers/l/0.jpg","cover_med":"https://img.radioparadise.com/covers/m/0.jpg","cover_small":"https://img.radioparadise.com/covers/s/0.jpg"} Dec 28 23:16:48 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:48 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:48 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:48 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/0.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":"The Universe Smiles Upon You ii","streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:48 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:48 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:48 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:16:48 volumio3 volumio[1156]: info: [1766963808261] [RadioParadise] setting new timer with duration of 10000 seconds. Dec 28 23:16:50 volumio3 volumio[1156]: info: Dec 28 23:16:50 volumio3 volumio[1156]: ---------------------------- MPD announces system playlist update Dec 28 23:16:50 volumio3 volumio[1156]: info: Ignoring MPD Status Update Dec 28 23:16:50 volumio3 volumio[1156]: info: Dec 28 23:16:50 volumio3 volumio[1156]: ---------------------------- MPD announces state update: player Dec 28 23:16:50 volumio3 volumio[1156]: info: ControllerMpd::getState Dec 28 23:16:50 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:16:50 volumio3 volumio[1156]: info: ------------------------------ 1ms Dec 28 23:16:50 volumio3 volumio[1156]: info: sendMpdCommand status took 1 milliseconds Dec 28 23:16:50 volumio3 volumio[1156]: verbose: ControllerMpd::parseState Dec 28 23:16:50 volumio3 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:16:50 volumio3 volumio[1156]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:16:50 volumio3 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:16:50 volumio3 volumio[1156]: info: ControllerMpd::pushState Dec 28 23:16:50 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:50 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:50 volumio3 volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 28 23:16:50 volumio3 volumio[1156]: info: ------------------------------ 3ms Dec 28 23:16:53 volumio3 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 23:16:53 volumio3 volumio[1156]: info: CoreCommandRouter::Close All Modals sent Dec 28 23:16:58 volumio3 volumio[1156]: info: [1766963818262] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=9 Dec 28 23:16:58 volumio3 volumio[1156]: info: [1766963818780] [RadioParadise] received new metadata: {"time":5,"artist":"","title":null,"album":"The Universe Smiles Upon You ii","year":null,"cover":"https://img.radioparadise.com/covers/l/0.jpg","cover_med":"https://img.radioparadise.com/covers/m/0.jpg","cover_small":"https://img.radioparadise.com/covers/s/0.jpg"} Dec 28 23:16:58 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreCommandRouter::servicePushState Dec 28 23:16:58 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:58 volumio3 volumio[1156]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/0.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":"The Universe Smiles Upon You ii","streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 28 23:16:58 volumio3 volumio[1156]: verbose: CURRENT POSITION 0 Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreStateMachine::syncState stateService play Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:16:58 volumio3 volumio[1156]: info: Received an update from plugin. extracting info from payload Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:58 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreStateMachine::pushState Dec 28 23:16:58 volumio3 volumio[1156]: info: CorePlayQueue::getTrack 0 Dec 28 23:16:58 volumio3 volumio[1156]: info: CoreCommandRouter::volumioPushState Dec 28 23:16:58 volumio3 volumio[1156]: info: [1766963818785] [RadioParadise] setting new timer with duration of 10000 seconds. Dec 28 23:16:58 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:58 volumio3 volumio[1156]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 28 23:16:58 volumio3 volumio[1156]: info: FusionDsp - Volumio is playing Dec 28 23:16:58 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:16:58 volumio3 volumio[1156]: info: FusionDsp - Clipping Monitor started Dec 28 23:17:04 volumio3 volumio[1156]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 23:17:04 volumio3 volumio[1156]: info: CoreCommandRouter::Close All Modals sent Dec 28 23:17:04 volumio3 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Dec 28 23:17:05 volumio3 volumio[1156]: info: Preload queue cleared Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth/motherearth Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Roze%2C%20roze%20(sve%C5%A1inieka%20zieds)%22%2C%22artist%22%3A%22Song%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FphBM2IDjEnTMqwd9qXHRGOCSkFhyLKlaNGXGjg47liNVfDZNnNwwCw-ZfvHg9wUFLpRYwG0-RtH4eQ0q%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YZ49xSlT2ys%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YZ49xSlT2ys%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Rozit%2C%20k%C4%81%20Tevi%20groz%C4%ABt%22%2C%22artist%22%3A%22Aivars%20Lap%C5%A1%C4%81ns%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F_S3IS_JwlDfnP0WL6aML5uRwOcDD9GkGJrNRLszUe_FcH099OAG-43WQ78PqTJnQRhIDWtOCl0aj0VE%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22MecVDaUWtoY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22MecVDaUWtoY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_instrumental/motherearth.instrumental Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22M%C4%81rti%C5%86roze%22%2C%22artist%22%3A%22Aldis%20Dr%C4%93%C4%A3eris%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FHWyAk9KbD_JYD_QRrQnhIdIXBJK654ycSH1PmWE-vhxZfN1z91qYqPOgep9HyGmbzmhOQNomUC7kvWgJzQ%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vSsGWCMVg90%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vSsGWCMVg90%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Ir%20atkal%20Ziemassv%C4%93tki%22%2C%22artist%22%3A%22Aldis%20Dr%C4%93%C4%A3eris%20%26%20Smaids%20no%20R%C4%ABgas%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FqUg2MEbTI3vmdK36U9EVVkQbQiK7QeUhneRM9fcJueAvdoqqbiVNC-JT1bU1P_J0YY16AfpXrxAqYA-0%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220_kBcriOr_o%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220_kBcriOr_o%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Mauku%20m%C4%81j%C4%81%20uguns%20sp%C4%ABd%22%2C%22artist%22%3A%22Aldis%20Dr%C4%93%C4%A3eris%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FNxWEc3e53sn76bieeCt8_rcqofDmpIRK8NPDcfzj9HhUqJNK8SGwl3z6Rfr5Aild3EvQBAto7oXTBw0x%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22GbzRBhApEcA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22GbzRBhApEcA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Zvaig%C5%BE%3Fu%20Lietus%22%2C%22artist%22%3A%22Zvaig%C5%BE%C5%86u%20Lietus%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F_ALVEW5dFOB76Wdv_VkEv0oKxSVuy5AzVrRgkpRSRzFhnbc4uT1vOVFs4eGpEGZ4PnLwQ9jGBZ3tkZNM%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22JBiuDnaLALk%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22JBiuDnaLALk%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Pop%C5%ABrijs%22%2C%22artist%22%3A%22Zvaig%C5%BE%C5%86u%20Lietus%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F0sZfofayJpbn7kjodmLeXlPpPW7ikm6y9JoUg0UEtTfjTxuhB2e7H14MhuFBu6n9KASPsu78dZiBkYCz%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22mlT19l1-bkw%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22mlT19l1-bkw%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Dz%C4%93rven%C4%ABte%22%2C%22artist%22%3A%22Zvaig%C5%BE%C5%86u%20Lietus%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F0sZfofayJpbn7kjodmLeXlPpPW7ikm6y9JoUg0UEtTfjTxuhB2e7H14MhuFBu6n9KASPsu78dZiBkYCz%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2266ubnXGyWQc%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2266ubnXGyWQc%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Dz%C4%93rven%C4%ABte%22%2C%22artist%22%3A%22Ferrum%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FgY8N5aArBYzS7wdE0NNPe1SGpCwjdRWcU_KSaP16dQ8YJW321yf6ysuFCub2ywoKiECfsx5aBYAWfkb9%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22auMfGw7i7VE%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22auMfGw7i7VE%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22V%C4%81rds%22%2C%22artist%22%3A%22Song%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FS-_ZfHWI7oDNAh-JTRcTHuDIm-UgqH9DZfkYrQ2XinOPpy10wv6ks-3ktAC4dsGdxjWqv16bOLUt5QEw_A%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%224M1Em7XjtB8%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%224M1Em7XjtB8%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Tuvojos%20Tev%22%2C%22artist%22%3A%22Jauna%20Slavas%20Dziesma%22%2C%22album%22%3A%22Evija%20%C4%8Cerevko%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FU_M1ZKAer3WwZG-oE9gzp8LAhg0b-vxZyBv3awQ9MTG5-AmGLiVnmZ8jUfybotxTrrw7FpMz17S2WrSCJw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kn3B6-gFpvI%22%2C%22playlistId%22%3A%22PLyqBHuAYJ6qFrVEJF5CkMNAr49UO_HjIU%22%2C%22playlistSetVideoId%22%3A%2252152B4946C2F73F%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PLyqBHuAYJ6qFrVEJF5CkMNAr49UO_HjIU%22%2C%22videoId%22%3A%22EZSenciI57k%22%7D%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Romeo%20And%20Juliet%20(Live%20At%20Hammersmith%20Odeon%2C%20London%20%2F%201983%20%2F%20Remastered%202023)%22%2C%22artist%22%3A%22Dire%20Straits%22%2C%22album%22%3A%22Live%201978%20-%201992%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fy-5D1W5iAwqgkX1osxW-mbKoKi_RfBDZs83UbS2CHth4hCvNDsVV5TOWuPtPNajPrf2WcOEQJx2uPyo%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%225xaiYpHh0nY%22%2C%22playlistId%22%3A%22OLAK5uy_mI7SYc1xgRHE4OFzQMLzBn8Posf1X_nvE%22%2C%22index%22%3A3%2C%22playlistSetVideoId%22%3A%2281003B0BD4B169C2%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_mI7SYc1xgRHE4OFzQMLzBn8Posf1X_nvE%22%2C%22videoId%22%3A%22diWPvvN0xTs%22%7D%7D%7D%7D Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: http://stream.radioparadise.com/global-flacm Dec 28 23:17:05 volumio3 volumio[1156]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Dec 28 23:17:05 volumio3 volumio[1156]: info: Exploding uri https://motherearth.streamserver24.com/listen/motherearth/motherearth in service motherearthradio Dec 28 23:17:05 volumio3 volumio[1156]: info: Exploding uri https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz in service motherearthradio Dec 28 23:17:05 volumio3 volumio[1156]: info: Exploding uri http://stream.radioparadise.com/beyond-flacm in service radio_paradise Dec 28 23:17:05 volumio3 volumio[1156]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/beyond-flacm" Dec 28 23:17:05 volumio3 volumio[1156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 23:17:05 volumio3 volumio[1156]: TypeError: Cannot read properties of undefined (reading 'then') Dec 28 23:17:05 volumio3 volumio[1156]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Dec 28 23:17:05 volumio3 volumio[1156]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 28 23:17:05 volumio3 volumio[1156]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 28 23:17:05 volumio3 volumio[1156]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Dec 28 23:17:05 volumio3 volumio[1156]: at listOnTimeout (node:internal/timers:573:17) Dec 28 23:17:05 volumio3 volumio[1156]: at process.processTimers (node:internal/timers:514:7) Dec 28 23:17:05 volumio3 volumio[1156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 23:17:05 volumio3 sudo[12328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 23:16' Dec 28 23:17:05 volumio3 sudo[12328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"