Dec 29 20:35:03 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Dec 29 20:35:03 volumio volumio[1345]: info: [1767040503562] [rad357pl] handleBrowseUri curUri: rad357pl Dec 29 20:35:03 volumio volumio[1345]: info: [1767040503562] [rad357pl] getRadioContent url: rad357pl Dec 29 20:35:03 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:06 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:06 volumio volumio[1345]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 20:35:06 volumio volumio[1345]: info: CoreStateMachine::ClearQueue Dec 29 20:35:06 volumio volumio[1345]: info: CoreStateMachine::stop Dec 29 20:35:06 volumio volumio[1345]: info: CoreStateMachine::serviceStop Dec 29 20:35:06 volumio volumio[1345]: info: CoreCommandRouter::serviceStop Dec 29 20:35:06 volumio volumio[1345]: info: [RTL-SDR Radio] Stopping all processes Dec 29 20:35:06 volumio sudo[11144]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f 'fn-rtl_fm -f' Dec 29 20:35:06 volumio sudo[11144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:06 volumio sudo[11144]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:06 volumio sudo[11148]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f 'aplay -D volumio' Dec 29 20:35:06 volumio sudo[11148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:07 volumio sudo[11148]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:07 volumio sudo[11152]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-redsea Dec 29 20:35:07 volumio sudo[11152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:07 volumio sudo[11152]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:07 volumio sudo[11156]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-dab Dec 29 20:35:07 volumio sudo[11156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:07 volumio sudo[11156]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:07 volumio sudo[11160]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-rtl_power Dec 29 20:35:07 volumio sudo[11160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:07 volumio sudo[11160]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:07 volumio sudo[11164]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-dab-scanner Dec 29 20:35:07 volumio sudo[11164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:07 volumio sudo[11164]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:07 volumio sudo[11168]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f sox Dec 29 20:35:07 volumio sudo[11168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:35:07 volumio sudo[11168]: pam_unix(sudo:session): session closed for user root Dec 29 20:35:07 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: scanning_dab -> idle Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::clearPlayQueue Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::saveQueue Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::volumioPushQueue Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::addQueueItems Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::addQueueItems Dec 29 20:35:07 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:07 volumio volumio[1345]: info: Adding Item to queue: webrns/0 Dec 29 20:35:07 volumio volumio[1345]: info: Exploding uri webrns/0 in service rad357pl Dec 29 20:35:07 volumio volumio[1345]: info: [1767040507383] [rad357pl] explodeUri: webrns/0 Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::volumioPushQueue Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::saveQueue Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::updateTrackBlock Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::getTrackBlock Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::volumioPlay Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::play index 0 Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::stop Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::updateTrackBlock Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::getTrackBlock Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::stPlaybackTimer Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::getTrack 25 Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 20:35:07 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::serviceStop Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::getTrack 25 Dec 29 20:35:07 volumio volumio[1345]: info: ControllerMpd::stop Dec 29 20:35:07 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 20:35:07 volumio volumio[1345]: info: sendMpdCommand stop took 12 milliseconds Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::play index undefined Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::getTrack 0 Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::startPlaybackTimer Dec 29 20:35:07 volumio volumio[1345]: info: CorePlayQueue::getTrack 0 Dec 29 20:35:07 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 20:35:07 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> idle Dec 29 20:35:07 volumio volumio[1345]: info: [RTL-SDR Radio] Browse URI: rtlsdr Dec 29 20:35:07 volumio volumio[1345]: info: sendMpdCommand stop took 6 milliseconds Dec 29 20:35:07 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 20:35:07 volumio volumio[1345]: info: Dec 29 20:35:07 volumio volumio[1345]: ---------------------------- MPD announces system playlist update Dec 29 20:35:07 volumio volumio[1345]: info: Ignoring MPD Status Update Dec 29 20:35:07 volumio volumio[1345]: info: sendMpdCommand clear took 1 milliseconds Dec 29 20:35:07 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Dec 29 20:35:07 volumio volumio[1345]: error: updateQueue error: null Dec 29 20:35:07 volumio volumio[1345]: info: Dec 29 20:35:07 volumio volumio[1345]: ---------------------------- MPD announces system playlist update Dec 29 20:35:07 volumio volumio[1345]: info: Ignoring MPD Status Update Dec 29 20:35:07 volumio volumio[1345]: info: ------------------------------ 3ms Dec 29 20:35:07 volumio volumio[1345]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 2 milliseconds Dec 29 20:35:07 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand play Dec 29 20:35:07 volumio volumio[1345]: info: ------------------------------ 6ms Dec 29 20:35:07 volumio volumio[1345]: info: sendMpdCommand play took 4 milliseconds Dec 29 20:35:07 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 20:35:07 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:07 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:08 volumio volumio[1345]: info: Dec 29 20:35:08 volumio volumio[1345]: ---------------------------- MPD announces state update: player Dec 29 20:35:08 volumio volumio[1345]: info: ControllerMpd::getState Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand status Dec 29 20:35:08 volumio volumio[1345]: info: Dec 29 20:35:08 volumio volumio[1345]: ---------------------------- MPD announces state update: player Dec 29 20:35:08 volumio volumio[1345]: info: sendMpdCommand status took 41 milliseconds Dec 29 20:35:08 volumio volumio[1345]: info: ControllerMpd::getState Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand status Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::parseState Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 20:35:08 volumio volumio[1345]: info: Dec 29 20:35:08 volumio volumio[1345]: ---------------------------- MPD announces system playlist update Dec 29 20:35:08 volumio volumio[1345]: info: Ignoring MPD Status Update Dec 29 20:35:08 volumio volumio[1345]: info: sendMpdCommand status took 2 milliseconds Dec 29 20:35:08 volumio volumio[1345]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::parseState Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::parseTrackInfo Dec 29 20:35:08 volumio volumio[1345]: info: ControllerMpd::pushState Dec 29 20:35:08 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:35:08 volumio volumio[1345]: info: CorePlayQueue::getTrack 0 Dec 29 20:35:08 volumio volumio[1345]: 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":"128 Kbps","isStreaming":false,"title":"Camo & Krooked - Good Times Bad Times","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 29 20:35:08 volumio volumio[1345]: verbose: CURRENT POSITION 0 Dec 29 20:35:08 volumio volumio[1345]: info: CoreStateMachine::syncState stateService play Dec 29 20:35:08 volumio volumio[1345]: info: CoreStateMachine::syncState currentStatus stop Dec 29 20:35:08 volumio volumio[1345]: info: ------------------------------ 46ms Dec 29 20:35:08 volumio volumio[1345]: info: ------------------------------ 4ms Dec 29 20:35:08 volumio volumio[1345]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 20:35:08 volumio volumio[1345]: verbose: ControllerMpd::parseTrackInfo Dec 29 20:35:08 volumio volumio[1345]: info: ControllerMpd::pushState Dec 29 20:35:08 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:35:08 volumio volumio[1345]: info: CorePlayQueue::getTrack 0 Dec 29 20:35:08 volumio volumio[1345]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Camo & Krooked - Good Times Bad Times","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 29 20:35:08 volumio volumio[1345]: verbose: CURRENT POSITION 0 Dec 29 20:35:08 volumio volumio[1345]: info: CoreStateMachine::syncState stateService play Dec 29 20:35:08 volumio volumio[1345]: info: CoreStateMachine::syncState currentStatus play Dec 29 20:35:08 volumio volumio[1345]: info: Received an update from plugin. extracting info from payload Dec 29 20:35:08 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:08 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 20:35:08 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:08 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:08 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:08 volumio volumio[1345]: info: ------------------------------ 21ms Dec 29 20:35:11 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , handleBrowseUri Dec 29 20:35:11 volumio volumio[1345]: info: [RTL-SDR Radio] Browse URI: rtlsdr://fm Dec 29 20:35:11 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:14 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::ClearQueue Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::stop Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::stPlaybackTimer Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::updateTrackBlock Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrackBlock Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 0 Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::serviceStop Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 0 Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::serviceStop Dec 29 20:35:14 volumio volumio[1345]: info: ControllerMpd::stop Dec 29 20:35:14 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::clearPlayQueue Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::saveQueue Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushQueue Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::addQueueItems Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::addQueueItems Dec 29 20:35:14 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/88.1 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/88.1 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/88.5 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/88.5 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/88.7 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/88.7 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/89.1 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/89.1 Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushQueue Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::saveQueue Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::updateTrackBlock Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrackBlock Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPlay Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::play index 3 Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::addQueueItems Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::addQueueItems Dec 29 20:35:14 volumio volumio[1345]: info: Preload queue cleared Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/89.3 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/89.3 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/89.4 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/89.4 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/90.6 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/90.6 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/90.7 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/90.7 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/91.0 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/91.0 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/91.5 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/91.5 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/91.7 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/91.7 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/92.0 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/92.0 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/93.0 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/93.0 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/93.3 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/93.3 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/93.6 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/93.6 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/94.2 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/94.2 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/94.5 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/94.5 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/95.8 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/95.8 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/96.1 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/96.1 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/96.8 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/96.8 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/97.1 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/97.1 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/98.4 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/98.4 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/98.7 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/98.7 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/99.4 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/99.4 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/99.7 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/99.7 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/100.8 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/100.8 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/101.0 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/101.0 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/101.3 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/101.3 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/101.9 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/101.9 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/102.2 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/102.2 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/103.1 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/103.1 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/103.5 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/103.5 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/103.8 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/103.8 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/104.5 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/104.5 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/104.8 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/104.8 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/106.1 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/106.1 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/106.4 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/106.4 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/107.0 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/107.0 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://fm/107.4 Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://fm/107.4 Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: rtlsdr://rescan Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: rtlsdr://rescan Dec 29 20:35:14 volumio volumio[1345]: info: Adding Item to queue: Dec 29 20:35:14 volumio volumio[1345]: info: Using cached record of: Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::stop Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushQueue Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::saveQueue Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::play index undefined Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::updateTrackBlock Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrackBlock Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 3 Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::startPlaybackTimer Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 3 Dec 29 20:35:14 volumio volumio[1345]: info: [RTL-SDR Radio] Play track: {"service":"rtlsdr_radio","type":"song","title":"FM 89.1","artist":"89.1 MHz","album":"Radio FM","albumart":"/albumart?sourceicon=music_service/rtlsdr_radio/assets/fm.svg","uri":"rtlsdr://fm/89.1"} Dec 29 20:35:14 volumio volumio[1345]: info: [RTL-SDR Radio] Playing FM station: 89.1 MHz Dec 29 20:35:14 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> playing_fm Dec 29 20:35:14 volumio volumio[1345]: info: Dec 29 20:35:14 volumio volumio[1345]: ---------------------------- MPD announces state update: player Dec 29 20:35:14 volumio volumio[1345]: info: sendMpdCommand stop took 24 milliseconds Dec 29 20:35:14 volumio volumio[1345]: info: ControllerMpd::getState Dec 29 20:35:14 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand status Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 3 Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 3 Dec 29 20:35:14 volumio volumio[1345]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rtlsdr_radio Received rad357pl Dec 29 20:35:14 volumio volumio[1345]: info: sendMpdCommand status took 6 milliseconds Dec 29 20:35:14 volumio volumio[1345]: verbose: ControllerMpd::parseState Dec 29 20:35:14 volumio volumio[1345]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 20:35:14 volumio volumio[1345]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 20:35:14 volumio volumio[1345]: verbose: ControllerMpd::parseTrackInfo Dec 29 20:35:14 volumio volumio[1345]: info: ControllerMpd::pushState Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 3 Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:14 volumio volumio[1345]: info: CorePlayQueue::getTrack 3 Dec 29 20:35:14 volumio volumio[1345]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rtlsdr_radio Received mpd Dec 29 20:35:14 volumio volumio[1345]: info: ------------------------------ 12ms Dec 29 20:35:14 volumio volumio[1345]: info: [RTL-SDR Radio] Saved stations database Dec 29 20:35:14 volumio volumio[1345]: info: [RTL-SDR Radio] Starting FM with RDS: fn-rtl_fm -f 89.1M -M fm -s 171k -l 0 -A std -g 100 -F 9 Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService rtlsdr_radio Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:35:14 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 20:35:14 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:15 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:35:15 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:35:27 volumio volumio[1345]: info: CoreCommandRouter::volumioGetState Dec 29 20:35:28 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 20:35:28 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 29 20:35:41 volumio volumio[1345]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 20:36:06 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio scanFm undefined Dec 29 20:36:06 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , scanFm Dec 29 20:36:06 volumio volumio[1345]: info: [RTL-SDR Radio] Device conflict: currently odtwarza radio FM, requested: scan_fm Dec 29 20:36:08 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio handleDeviceConflict [object Object] Dec 29 20:36:08 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , handleDeviceConflict Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Device conflict resolution: cancel for scan_fm Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Queue cleared due to explicit cancel Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Stopping current operation: playing_fm Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Stopping all processes Dec 29 20:36:08 volumio sudo[11471]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f 'fn-rtl_fm -f' Dec 29 20:36:08 volumio sudo[11471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11471]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio sudo[11475]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f 'aplay -D volumio' Dec 29 20:36:08 volumio sudo[11475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11475]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio sudo[11479]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-redsea Dec 29 20:36:08 volumio sudo[11479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11479]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio sudo[11483]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-dab Dec 29 20:36:08 volumio sudo[11483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11483]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio sudo[11487]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-rtl_power Dec 29 20:36:08 volumio sudo[11487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11487]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio sudo[11491]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-dab-scanner Dec 29 20:36:08 volumio sudo[11491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11491]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio sudo[11495]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f sox Dec 29 20:36:08 volumio sudo[11495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:08 volumio sudo[11495]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: playing_fm -> idle Dec 29 20:36:08 volumio volumio[1345]: info: CoreCommandRouter::servicePushState Dec 29 20:36:08 volumio volumio[1345]: info: CoreStateMachine::pushState Dec 29 20:36:08 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 20:36:08 volumio volumio[1345]: info: CoreCommandRouter::volumioPushState Dec 29 20:36:08 volumio volumio[1345]: info: CoreStateMachine::setConsumeUpdateService Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> idle Dec 29 20:36:08 volumio volumio[1345]: info: [RTL-SDR Radio] Waiting for device cleanup... Dec 29 20:36:09 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> scanning_fm Dec 29 20:36:09 volumio volumio[1345]: info: [RTL-SDR Radio] Starting FM scan... Dec 29 20:36:09 volumio volumio[1345]: info: [RTL-SDR Radio] Scan command: fn-rtl_power -f 87.5M:108M:125k -i 10 -1 /tmp/fm_scan_1767040569957.csv Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Scan complete, parsing results... Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Processing 8 frequency hops Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Combined spectrum: 192 valid bins Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Average power: -61.6 dBm, threshold: -58.6 dBm (+3 dB) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 88.1 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 88.5 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 89.1 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 89.4 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 90.7 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 91.0 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 91.7 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 92.0 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 93.3 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 93.6 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 94.2 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 94.5 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 95.8 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 96.1 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 96.8 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 97.1 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 98.4 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 98.7 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 99.4 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 99.7 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 101.0 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 101.3 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 101.9 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 102.2 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 103.5 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 103.8 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 104.5 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 104.8 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 106.1 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 106.4 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 107.0 MHz (-57.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found station: 107.4 MHz (-58.1 dBm) Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Found 32 FM stations Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Merging FM scan results with existing database Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 88.7 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 89.3 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 90.6 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 91.5 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 93.0 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 100.8 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Keeping existing FM station not in scan: 103.1 MHz Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] FM merge complete: 32 scanned, 39 total, 0 reappeared Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Saved stations database Dec 29 20:36:20 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: scanning_fm -> idle Dec 29 20:36:22 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio saveFmSettings [object Object] Dec 29 20:36:22 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , saveFmSettings Dec 29 20:36:34 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio scanDab undefined Dec 29 20:36:34 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , scanDab Dec 29 20:36:34 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> scanning_dab Dec 29 20:36:34 volumio volumio[1345]: info: [RTL-SDR Radio] Starting DAB scan... Dec 29 20:36:34 volumio volumio[1345]: info: [RTL-SDR Radio] DAB scan command: fn-dab-scanner -B BAND_III -G 80 -j > /tmp/dab_scan_1767040594474.json Dec 29 20:36:47 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio scanDab undefined Dec 29 20:36:47 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , scanDab Dec 29 20:36:47 volumio volumio[1345]: info: [RTL-SDR Radio] Device conflict: currently skanuje stacje DAB, requested: scan_dab Dec 29 20:36:53 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio handleDeviceConflict [object Object] Dec 29 20:36:53 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , handleDeviceConflict Dec 29 20:36:53 volumio volumio[1345]: info: [RTL-SDR Radio] Device conflict resolution: cancel for scan_dab Dec 29 20:36:53 volumio volumio[1345]: info: [RTL-SDR Radio] Queue cleared due to explicit cancel Dec 29 20:36:53 volumio volumio[1345]: info: [RTL-SDR Radio] Stopping current operation: scanning_dab Dec 29 20:36:53 volumio volumio[1345]: info: [RTL-SDR Radio] Stopping all processes Dec 29 20:36:53 volumio sudo[11732]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f 'fn-rtl_fm -f' Dec 29 20:36:53 volumio sudo[11732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:53 volumio sudo[11732]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:53 volumio sudo[11736]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f 'aplay -D volumio' Dec 29 20:36:53 volumio sudo[11736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:53 volumio sudo[11736]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:53 volumio sudo[11740]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-redsea Dec 29 20:36:53 volumio sudo[11740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:53 volumio sudo[11740]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:53 volumio sudo[11744]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-dab Dec 29 20:36:53 volumio sudo[11744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:53 volumio sudo[11744]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:53 volumio sudo[11748]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-rtl_power Dec 29 20:36:53 volumio sudo[11748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:53 volumio sudo[11748]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:53 volumio sudo[11752]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f fn-dab-scanner Dec 29 20:36:53 volumio sudo[11752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:53 volumio sudo[11752]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:53 volumio sudo[11756]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/pkill -f sox Dec 29 20:36:53 volumio sudo[11756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 20:36:54 volumio sudo[11756]: pam_unix(sudo:session): session closed for user root Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: scanning_dab -> idle Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Waiting for device cleanup... Dec 29 20:36:54 volumio volumio[1345]: info: CALLMETHOD: music_service rtlsdr_radio handleDeviceConflict [object Object] Dec 29 20:36:54 volumio volumio[1345]: info: CoreCommandRouter::executeOnPlugin: rtlsdr_radio , handleDeviceConflict Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Device conflict resolution: cancel for scan_dab Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Queue cleared due to explicit cancel Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Stopping current operation: idle Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Waiting for device cleanup... Dec 29 20:36:54 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> idle Dec 29 20:36:55 volumio volumio[1345]: info: [RTL-SDR Radio] Device state: idle -> scanning_dab Dec 29 20:36:55 volumio volumio[1345]: info: [RTL-SDR Radio] Starting DAB scan... Dec 29 20:36:55 volumio volumio[1345]: info: [RTL-SDR Radio] DAB scan command: fn-dab-scanner -B BAND_III -G 80 -j > /tmp/dab_scan_1767040615244.json Dec 29 20:36:55 volumio volumio[1345]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 20:36:55 volumio volumio[1345]: Error: Unable to resolve or reject the same promise twice Dec 29 20:36:55 volumio volumio[1345]: at Promise.resolve (/data/plugins/music_service/rtlsdr_radio/node_modules/kew/kew.js:140:43) Dec 29 20:36:55 volumio volumio[1345]: at Timeout._onTimeout (/data/plugins/music_service/rtlsdr_radio/index.js:3042:27) Dec 29 20:36:55 volumio volumio[1345]: at listOnTimeout (node:internal/timers:573:17) Dec 29 20:36:55 volumio volumio[1345]: at process.processTimers (node:internal/timers:514:7) Dec 29 20:36:55 volumio volumio[1345]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 20:36:55 volumio sudo[11780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 20:35' Dec 29 20:36:55 volumio sudo[11780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"