Mar 04 21:07:19 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , addMyWebRadio Mar 04 21:07:19 volumio volumio[1188]: info: {"name":"A Mississippi Blues","uri":"http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333"} Mar 04 21:07:19 volumio volumio[1188]: info: Saving local item my-web-radio Mar 04 21:07:23 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 21:07:23 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:07:28 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Mar 04 21:07:28 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:07:28 volumio volumio[1188]: info: Adding Item to queue: http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333 Mar 04 21:07:28 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 21:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Mar 04 21:07:28 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::play index 0 Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:28 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:07:28 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:28 volumio volumio[1188]: info: [1772654848648] ControllerWebradio::clearAddPlayTrack Mar 04 21:07:28 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:07:28 volumio volumio[1188]: info: sendMpdCommand stop took 3 milliseconds Mar 04 21:07:28 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:07:28 volumio volumio[1188]: info: Mar 04 21:07:28 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:07:28 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:07:28 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Mar 04 21:07:28 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333" Mar 04 21:07:28 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:07:28 volumio volumio[1188]: info: ------------------------------ 1ms Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333" Mar 04 21:07:29 volumio volumio[1188]: info: Mar 04 21:07:29 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:07:29 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand add "http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333" took 1 milliseconds Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:07:29 volumio volumio[1188]: info: ------------------------------ 3ms Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:07:29 volumio volumio[1188]: info: Mar 04 21:07:29 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:07:29 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:07:29 volumio volumio[1188]: info: Mar 04 21:07:29 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand status took 20 milliseconds Mar 04 21:07:29 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:07:29 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:07:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:29 volumio volumio[1188]: 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":";?type=http&nocache=1746940333","artist":null,"album":null,"uri":"http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333","trackType":""} Mar 04 21:07:29 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:07:29 volumio volumio[1188]: info: ------------------------------ 26ms Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:07:29 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:07:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:29 volumio volumio[1188]: 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":";?type=http&nocache=1746940333","artist":null,"album":null,"uri":"http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333","trackType":""} Mar 04 21:07:29 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Mar 04 21:07:29 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:07:29 volumio volumio[1188]: info: ------------------------------ 18ms Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:07:29 volumio volumio[1188]: info: Mar 04 21:07:29 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:07:29 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:07:29 volumio volumio[1188]: info: Mar 04 21:07:29 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:07:29 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:07:29 volumio volumio[1188]: info: ------------------------------ 4ms Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:07:29 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 21:07:29 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:07:29 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:07:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:29 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1053,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":";?type=http&nocache=1746940333","artist":"A MISSISSIPPI BLUES","album":null,"uri":"http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333","trackType":""} Mar 04 21:07:29 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Mar 04 21:07:29 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:07:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:07:29 volumio volumio[1188]: info: ------------------------------ 21ms Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:07:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:07:33 volumio volumio[1188]: info: Saving local item radio-favourites Mar 04 21:07:33 volumio volumio[1188]: info: Pushing Favourites {"uri":"http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333","favourite":false} Mar 04 21:07:38 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio Mar 04 21:07:38 volumio volumio[1188]: info: {"name":"Boss Radio 66"} Mar 04 21:07:38 volumio volumio[1188]: info: Saving local item my-web-radio Mar 04 21:07:38 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 21:07:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 21:07:45 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:07:47 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 21:07:47 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:06 volumio volumio[1188]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 21:08:06 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 21:08:06 volumio volumio[1188]: info: CoreCommandRouter::volumioGetQueue Mar 04 21:08:06 volumio volumio[1188]: info: CoreStateMachine::getQueue Mar 04 21:08:06 volumio volumio[1188]: info: CorePlayQueue::getQueue Mar 04 21:08:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 21:08:07 volumio volumio[1188]: info: CURURI: artists:// Mar 04 21:08:07 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:13 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 21:08:13 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:16 volumio volumio[1188]: Searching plugin music_service/webradio Mar 04 21:08:16 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 04 21:08:16 volumio volumio[1188]: info: An error occurred while querying SHOUTCAST Mar 04 21:08:17 volumio volumio[1188]: info: All search sources collected, pushing search results Mar 04 21:08:18 volumio volumio[1188]: Searching plugin music_service/webradio Mar 04 21:08:18 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 04 21:08:19 volumio volumio[1188]: info: All search sources collected, pushing search results Mar 04 21:08:21 volumio volumio[1188]: Searching plugin music_service/webradio Mar 04 21:08:21 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 04 21:08:22 volumio volumio[1188]: info: All search sources collected, pushing search results Mar 04 21:08:22 volumio volumio[1188]: Searching plugin music_service/webradio Mar 04 21:08:22 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 04 21:08:23 volumio volumio[1188]: info: All search sources collected, pushing search results Mar 04 21:08:24 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::serviceStop Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::serviceStop Mar 04 21:08:24 volumio volumio[1188]: info: [1772654904802] ControllerWebradio::stop Mar 04 21:08:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Mar 04 21:08:24 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:24 volumio volumio[1188]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s114109 Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:24 volumio volumio[1188]: info: Mar 04 21:08:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:24 volumio volumio[1188]: info: sendMpdCommand stop took 33 milliseconds Mar 04 21:08:24 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:24 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Mar 04 21:08:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 04 21:08:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:24 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";?type=http&nocache=1746940333","artist":"A MISSISSIPPI BLUES","album":null,"uri":"http://cast1.torontocast.com:4450/;?type=http&nocache=1746940333","trackType":""} Mar 04 21:08:24 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:24 volumio volumio[1188]: info: No code Mar 04 21:08:24 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:24 volumio volumio[1188]: info: ------------------------------ 29ms Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:24 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:25 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:08:25 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Mar 04 21:08:25 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Mar 04 21:08:25 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::play index 0 Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:25 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:25 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:25 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:25 volumio volumio[1188]: info: [1772654905217] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:25 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:25 volumio volumio[1188]: info: sendMpdCommand stop took 2 milliseconds Mar 04 21:08:25 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:25 volumio volumio[1188]: info: Mar 04 21:08:25 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:25 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:25 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Mar 04 21:08:25 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:25 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:25 volumio volumio[1188]: info: ------------------------------ 2ms Mar 04 21:08:27 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:27 volumio volumio[1188]: info: Mar 04 21:08:27 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:27 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:27 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 2 milliseconds Mar 04 21:08:27 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:27 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:27 volumio volumio[1188]: info: ------------------------------ 3ms Mar 04 21:08:27 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:08:29 volumio volumio[1188]: info: Mar 04 21:08:29 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:29 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - ---- read samplerate from file: 48000 Mar 04 21:08:29 volumio volumio[1188]: info: camilladsp stopping service pid 5911... Mar 04 21:08:29 volumio volumio[1188]: grep: /proc/5911/cmdline: binary file matches Mar 04 21:08:29 volumio volumio[1188]: info: camilladsp service terminated, instance 1 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: camilladsp service started and running in background, instance 1 Mar 04 21:08:29 volumio volumio[1188]: info: Mar 04 21:08:29 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand status took 142 milliseconds Mar 04 21:08:29 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:29 volumio volumio[1188]: info: Mar 04 21:08:29 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:29 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand status took 9 milliseconds Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:29 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 04 21:08:29 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:29 volumio volumio[1188]: info: No code Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:29 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","artist":null,"album":null,"uri":"https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","trackType":""} Mar 04 21:08:29 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:08:29 volumio volumio[1188]: info: ------------------------------ 30ms Mar 04 21:08:29 volumio volumio[1188]: info: ------------------------------ 170ms Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand status took 30 milliseconds Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:29 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 04 21:08:29 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: info: [1772654909575] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:29 volumio volumio[1188]: info: ------------------------------ 47ms Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:29 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand stop took 56 milliseconds Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:29 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Mar 04 21:08:29 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:29 volumio volumio[1188]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s114109 Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Mar 04 21:08:29 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::play index 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:29 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:29 volumio volumio[1188]: info: [1772654909643] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:29 volumio volumio[1188]: info: Mar 04 21:08:29 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:29 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand clear took 23 milliseconds Mar 04 21:08:29 volumio volumio[1188]: info: sendMpdCommand stop took 10 milliseconds Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:29 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:29 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:29 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:29 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:29 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:29 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:29 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:29 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:29 volumio volumio[1188]: info: ------------------------------ 15ms Mar 04 21:08:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:31 volumio volumio[1188]: info: Mar 04 21:08:31 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:31 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:31 volumio volumio[1188]: info: sendMpdCommand clear took 1985 milliseconds Mar 04 21:08:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:33 volumio volumio[1188]: info: Mar 04 21:08:33 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:33 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:33 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 1996 milliseconds Mar 04 21:08:33 volumio volumio[1188]: info: ------------------------------ 1996ms Mar 04 21:08:33 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:33 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:33 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:33 volumio volumio[1188]: info: Mar 04 21:08:33 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:33 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:33 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 2 milliseconds Mar 04 21:08:33 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:33 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:33 volumio volumio[1188]: info: ------------------------------ 5ms Mar 04 21:08:33 volumio volumio[1188]: info: sendMpdCommand play took 4 milliseconds Mar 04 21:08:33 volumio volumio[1188]: info: ------------------------------ 2ms Mar 04 21:08:33 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:08:35 volumio volumio[1188]: info: Mar 04 21:08:35 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:35 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - ---- read samplerate, raw: 12000,S32_LE,2,32 Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - ---- read samplerate from file: 12000 Mar 04 21:08:35 volumio volumio[1188]: info: camilladsp stopping service pid 5930... Mar 04 21:08:35 volumio volumio[1188]: grep: /proc/5930/cmdline: binary file matches Mar 04 21:08:35 volumio volumio[1188]: grep: /proc/5930/cmdline: binary file matches Mar 04 21:08:35 volumio volumio[1188]: info: camilladsp service terminated, instance 1 Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:35 volumio volumio[1188]: info: camilladsp service started and running in background, instance 1 Mar 04 21:08:35 volumio volumio[1188]: info: Mar 04 21:08:35 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:35 volumio volumio[1188]: info: sendMpdCommand status took 246 milliseconds Mar 04 21:08:35 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:35 volumio volumio[1188]: info: Mar 04 21:08:35 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:35 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:35 volumio volumio[1188]: info: sendMpdCommand status took 27 milliseconds Mar 04 21:08:35 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:35 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:35 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:35 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"12 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","artist":null,"album":null,"uri":"https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","trackType":""} Mar 04 21:08:35 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:08:35 volumio volumio[1188]: info: ------------------------------ 279ms Mar 04 21:08:35 volumio volumio[1188]: info: sendMpdCommand status took 9 milliseconds Mar 04 21:08:35 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:35 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:35 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:35 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","artist":null,"album":null,"uri":"https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","trackType":""} Mar 04 21:08:35 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Mar 04 21:08:35 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:35 volumio volumio[1188]: info: ------------------------------ 54ms Mar 04 21:08:35 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 04 21:08:35 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:35 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:35 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:35 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","artist":null,"album":null,"uri":"https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","trackType":""} Mar 04 21:08:35 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Mar 04 21:08:35 volumio volumio[1188]: info: Received an update from plugin. extracting info from payload Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:35 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:35 volumio volumio[1188]: info: ------------------------------ 43ms Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:35 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:36 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:36 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:36 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:36 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:36 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::serviceStop Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::serviceStop Mar 04 21:08:36 volumio volumio[1188]: info: [1772654916842] ControllerWebradio::stop Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Mar 04 21:08:36 volumio volumio[1188]: info: Preload queue cleared Mar 04 21:08:36 volumio volumio[1188]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s114109 Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::saveQueue Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Mar 04 21:08:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::play index 0 Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::stop Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:36 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:36 volumio volumio[1188]: info: [1772654916853] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:36 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:36 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:36 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:36 volumio volumio[1188]: info: Mar 04 21:08:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:36 volumio volumio[1188]: info: sendMpdCommand stop took 25 milliseconds Mar 04 21:08:36 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:36 volumio volumio[1188]: info: sendMpdCommand stop took 14 milliseconds Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:36 volumio volumio[1188]: info: Mar 04 21:08:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:36 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:36 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Mar 04 21:08:36 volumio volumio[1188]: info: sendMpdCommand clear took 2 milliseconds Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:36 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:36 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:36 volumio volumio[1188]: info: ------------------------------ 5ms Mar 04 21:08:36 volumio volumio[1188]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Mar 04 21:08:36 volumio volumio[1188]: info: ------------------------------ 8ms Mar 04 21:08:37 volumio volumio[1188]: 0+0 records in Mar 04 21:08:37 volumio volumio[1188]: 0+0 records out Mar 04 21:08:37 volumio volumio[1188]: 0 bytes copied, 0.000278436 s, 0.0 kB/s Mar 04 21:08:38 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:38 volumio volumio[1188]: info: Mar 04 21:08:38 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:38 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:38 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 2 milliseconds Mar 04 21:08:38 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:38 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:38 volumio volumio[1188]: info: ------------------------------ 2ms Mar 04 21:08:38 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:08:39 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Mar 04 21:08:39 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:39 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:39 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:39 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:39 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:39 volumio volumio[1188]: info: [1772654919368] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:39 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:39 volumio volumio[1188]: info: Mar 04 21:08:39 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:39 volumio volumio[1188]: info: sendMpdCommand stop took 14 milliseconds Mar 04 21:08:39 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:39 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:39 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:39 volumio volumio[1188]: info: Mar 04 21:08:39 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:39 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:39 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Mar 04 21:08:39 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Mar 04 21:08:39 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:39 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:39 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:39 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:39 volumio volumio[1188]: info: ------------------------------ 2ms Mar 04 21:08:39 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 04 21:08:41 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 2279 milliseconds Mar 04 21:08:41 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:41 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:41 volumio volumio[1188]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 21:08:41 volumio volumio[1188]: info: ------------------------------ 2285ms Mar 04 21:08:41 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 2 milliseconds Mar 04 21:08:41 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:41 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:41 volumio volumio[1188]: info: Mar 04 21:08:41 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:41 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:41 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:08:41 volumio volumio[1188]: info: ------------------------------ 2ms Mar 04 21:08:43 volumio volumio[1188]: info: Mar 04 21:08:43 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:43 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - ---- read samplerate, raw: 16000,S32_LE,2,32 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - ---- read samplerate from file: 16000 Mar 04 21:08:43 volumio volumio[1188]: info: camilladsp service terminated, instance 1 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: camilladsp service started and running in background, instance 1 Mar 04 21:08:43 volumio volumio[1188]: info: Mar 04 21:08:43 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:43 volumio volumio[1188]: info: sendMpdCommand status took 21 milliseconds Mar 04 21:08:43 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 21:08:43 volumio volumio[1188]: info: Mar 04 21:08:43 volumio volumio[1188]: ---------------------------- MPD announces state update: player Mar 04 21:08:43 volumio volumio[1188]: info: ControllerMpd::getState Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Mar 04 21:08:43 volumio volumio[1188]: info: sendMpdCommand status took 5 milliseconds Mar 04 21:08:43 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Mar 04 21:08:43 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 04 21:08:43 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:43 volumio volumio[1188]: info: No code Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:43 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"16 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","artist":null,"album":null,"uri":"https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1","trackType":""} Mar 04 21:08:43 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::syncState stateService play Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Mar 04 21:08:43 volumio volumio[1188]: info: ------------------------------ 20ms Mar 04 21:08:43 volumio volumio[1188]: info: ------------------------------ 43ms Mar 04 21:08:43 volumio volumio[1188]: info: sendMpdCommand status took 18 milliseconds Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::parseState Mar 04 21:08:43 volumio volumio[1188]: info: ControllerMpd::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 04 21:08:43 volumio volumio[1188]: verbose: CURRENT POSITION 0 Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus play Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: info: [1772654923814] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:43 volumio volumio[1188]: info: ------------------------------ 33ms Mar 04 21:08:43 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:43 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:43 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:43 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:43 volumio volumio[1188]: info: sendMpdCommand stop took 45 milliseconds Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:43 volumio volumio[1188]: info: Mar 04 21:08:43 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:43 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:43 volumio volumio[1188]: info: sendMpdCommand clear took 8 milliseconds Mar 04 21:08:43 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:43 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:43 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:43 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:43 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:43 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:43 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:44 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Mar 04 21:08:44 volumio volumio[1188]: info: CoreStateMachine::play index undefined Mar 04 21:08:44 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 21:08:44 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:44 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Mar 04 21:08:44 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Mar 04 21:08:44 volumio volumio[1188]: info: [1772654924229] ControllerWebradio::clearAddPlayTrack Mar 04 21:08:44 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 21:08:44 volumio volumio[1188]: 0+0 records in Mar 04 21:08:44 volumio volumio[1188]: 0+0 records out Mar 04 21:08:44 volumio volumio[1188]: 0 bytes copied, 0.000140989 s, 0.0 kB/s Mar 04 21:08:45 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 04 21:08:45 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 04 21:08:45 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:45 volumio volumio[1188]: info: ------------------------------ 2060ms Mar 04 21:08:45 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:45 volumio volumio[1188]: info: sendMpdCommand stop took 1699 milliseconds Mar 04 21:08:45 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 21:08:45 volumio volumio[1188]: info: Mar 04 21:08:45 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:45 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:45 volumio volumio[1188]: info: Mar 04 21:08:45 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:45 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:45 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 2 milliseconds Mar 04 21:08:45 volumio volumio[1188]: info: sendMpdCommand clear took 2 milliseconds Mar 04 21:08:45 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:45 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:45 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand load "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:45 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:45 volumio volumio[1188]: error: updateQueue error: null Mar 04 21:08:45 volumio volumio[1188]: info: ------------------------------ 3ms Mar 04 21:08:45 volumio volumio[1188]: info: ------------------------------ 3ms Mar 04 21:08:45 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:08:47 volumio volumio[1188]: info: VolumeController::SetAlsaVolume60 Mar 04 21:08:47 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:47 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:47 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:47 volumio volumio[1188]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Mar 04 21:08:47 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:47 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:47 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" Mar 04 21:08:48 volumio volumio[1188]: info: Mar 04 21:08:48 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Mar 04 21:08:48 volumio volumio[1188]: info: Ignoring MPD Status Update Mar 04 21:08:48 volumio volumio[1188]: info: sendMpdCommand add "https://tunein-live-c.cdnstream1.com/10716_96.mp3?aw_0_1st.skey=1772654904&aw_0_1st.abtest=&aw_0_1st.stationId=s114109&aw_0_1st.premium=false&source=TuneIn&aw_0_req.gdpr=true&aw_0_1st.platform=tunein&aw_0_1st.genre_id=g407&aw_0_1st.class=music&aw_0_1st.ads_partner_alias=ce.Other&aw_0_azn.planguage=%5biv%2c+en%5d&aw_0_1st.is_ondemand=false&aw_0_1st.topicId=na&aw_0_1st.affiliateIds=a40371%2ca39110&aw_0_1st.bandId=16&delivery=1" took 2 milliseconds Mar 04 21:08:48 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 21:08:48 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Mar 04 21:08:48 volumio volumio[1188]: info: ------------------------------ 3ms Mar 04 21:08:48 volumio volumio[1188]: info: sendMpdCommand play took 2 milliseconds Mar 04 21:08:48 volumio volumio[1188]: info: VolumeController::SetAlsaVolume75 Mar 04 21:08:48 volumio volumio[1188]: info: CoreStateMachine::pushState Mar 04 21:08:48 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 21:08:48 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Mar 04 21:08:48 volumio volumio[1188]: info: FusionDsp - volume level for loudness 75 gain applied 0.00 Mar 04 21:08:48 volumio volumio[1188]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 21:08:48 volumio volumio[1188]: info: FusionDsp - Loudness is ON true Mar 04 21:08:48 volumio volumio[1188]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 04 21:08:49 volumio volumio[1188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 21:08:49 volumio volumio[1188]: TypeError: Cannot read properties of null (reading 'slice') Mar 04 21:08:49 volumio volumio[1188]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 04 21:08:49 volumio volumio[1188]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 04 21:08:49 volumio volumio[1188]: at Object.onceWrapper (node:events:629:26) Mar 04 21:08:49 volumio volumio[1188]: at ClientRequest.emit (node:events:514:28) Mar 04 21:08:49 volumio volumio[1188]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 04 21:08:49 volumio volumio[1188]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 04 21:08:49 volumio volumio[1188]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 04 21:08:49 volumio volumio[1188]: at TLSSocket.emit (node:events:514:28) Mar 04 21:08:49 volumio volumio[1188]: at addChunk (node:internal/streams/readable:343:12) Mar 04 21:08:49 volumio volumio[1188]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 04 21:08:49 volumio volumio[1188]: at Readable.push (node:internal/streams/readable:253:10) Mar 04 21:08:49 volumio volumio[1188]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 04 21:08:49 volumio volumio[1188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 21:08:50 volumio sudo[6052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 21:07' Mar 04 21:08:50 volumio sudo[6052]: 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"