Feb 07 16:27:00 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:00 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:00 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:00 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:00 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:05 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:05 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:05 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:05 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:05 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:10 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:10 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:10 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:10 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:10 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:16 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:16 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:16 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:16 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:16 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:21 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:21 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:21 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:21 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:21 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:26 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:26 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:26 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:26 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:26 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:31 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:31 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:31 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:31 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:31 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:36 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:36 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:36 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:36 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:36 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:42 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:42 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:42 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:42 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:42 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:47 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:47 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:47 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:47 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:47 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:52 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:52 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:52 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:52 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:52 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:27:56 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 16:27:56 volumio volumio[1265]: info: Received Get System Info Feb 07 16:27:56 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 16:27:56 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 16:27:56 volumio volumio[1265]: info: Discovery: Getting this device information Feb 07 16:27:56 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Feb 07 16:27:56 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:27:56 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 16:27:57 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:57 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:57 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:27:57 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:27:57 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:02 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:02 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:02 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:02 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:02 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Feb 07 16:28:06 volumio volumio[1265]: info: Preload queue cleared Feb 07 16:28:08 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:08 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:08 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:08 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:08 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:11 volumio volumio[1265]: info: Preload queue cleared Feb 07 16:28:11 volumio volumio[1265]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::ClearQueue Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::stop Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::clearPlayQueue Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::saveQueue Feb 07 16:28:11 volumio volumio[1265]: info: CoreCommandRouter::volumioPushQueue Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::addQueueItems Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::addQueueItems Feb 07 16:28:11 volumio volumio[1265]: info: Preload queue cleared Feb 07 16:28:11 volumio volumio[1265]: info: Adding Item to queue: webrp/12 Feb 07 16:28:11 volumio volumio[1265]: info: Exploding uri webrp/12 in service radio_paradise Feb 07 16:28:11 volumio volumio[1265]: info: CoreCommandRouter::volumioPushQueue Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::saveQueue Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::updateTrackBlock Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::getTrackBlock Feb 07 16:28:11 volumio volumio[1265]: info: CoreCommandRouter::volumioPlay Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::play index 0 Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::stop Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::play index undefined Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::startPlaybackTimer Feb 07 16:28:11 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:11 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 16:28:11 volumio volumio[1265]: info: sendMpdCommand stop took 5 milliseconds Feb 07 16:28:11 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 16:28:11 volumio volumio[1265]: info: Feb 07 16:28:11 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Feb 07 16:28:11 volumio volumio[1265]: info: Ignoring MPD Status Update Feb 07 16:28:11 volumio volumio[1265]: info: sendMpdCommand clear took 2 milliseconds Feb 07 16:28:11 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/serenity" Feb 07 16:28:11 volumio volumio[1265]: info: Feb 07 16:28:11 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Feb 07 16:28:11 volumio volumio[1265]: info: Ignoring MPD Status Update Feb 07 16:28:11 volumio volumio[1265]: error: updateQueue error: null Feb 07 16:28:11 volumio volumio[1265]: info: Feb 07 16:28:11 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Feb 07 16:28:11 volumio volumio[1265]: info: Ignoring MPD Status Update Feb 07 16:28:11 volumio volumio[1265]: info: ------------------------------ 6ms Feb 07 16:28:11 volumio volumio[1265]: info: sendMpdCommand add "http://stream.radioparadise.com/serenity" took 5 milliseconds Feb 07 16:28:11 volumio volumio[1265]: info: ------------------------------ 4ms Feb 07 16:28:11 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Feb 07 16:28:11 volumio volumio[1265]: info: Feb 07 16:28:11 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Feb 07 16:28:11 volumio volumio[1265]: info: Ignoring MPD Status Update Feb 07 16:28:11 volumio volumio[1265]: info: ------------------------------ 10ms Feb 07 16:28:11 volumio volumio[1265]: info: sendMpdCommand play took 6 milliseconds Feb 07 16:28:11 volumio volumio[1265]: info: ------------------------------ 5ms Feb 07 16:28:11 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces state update: player Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::getState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces state update: player Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::getState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Feb 07 16:28:12 volumio volumio[1265]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 07 16:28:12 volumio volumio[1265]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 07 16:28:12 volumio volumio[1265]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces state update: player Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Feb 07 16:28:12 volumio volumio[1265]: info: Ignoring MPD Status Update Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces state update: player Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand status took 26 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::getState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand status took 24 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::getState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces state update: player Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::getState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand status took 19 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 24ms Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand status took 20 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand status took 8 milliseconds Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseTrackInfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseTrackInfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::servicePushState Feb 07 16:28:12 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:12 volumio volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 07 16:28:12 volumio volumio[1265]: verbose: CURRENT POSITION 0 Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState stateService play Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::servicePushState Feb 07 16:28:12 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:12 volumio volumio[1265]: 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":"64 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 07 16:28:12 volumio volumio[1265]: verbose: CURRENT POSITION 0 Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState stateService play Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState currentStatus play Feb 07 16:28:12 volumio volumio[1265]: info: Received an update from plugin. extracting info from payload Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 81ms Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 93ms Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Feb 07 16:28:12 volumio volumio[1265]: info: Ignoring MPD Status Update Feb 07 16:28:12 volumio volumio[1265]: info: Feb 07 16:28:12 volumio volumio[1265]: ---------------------------- MPD announces state update: player Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::getState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand playlistinfo took 57 milliseconds Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 16ms Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand status took 9 milliseconds Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseTrackInfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseTrackInfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseTrackInfo Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseState Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::servicePushState Feb 07 16:28:12 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:12 volumio volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 07 16:28:12 volumio volumio[1265]: verbose: CURRENT POSITION 0 Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState stateService play Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState currentStatus play Feb 07 16:28:12 volumio volumio[1265]: info: Received an update from plugin. extracting info from payload Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::servicePushState Feb 07 16:28:12 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:12 volumio volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 07 16:28:12 volumio volumio[1265]: verbose: CURRENT POSITION 0 Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState stateService play Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState currentStatus play Feb 07 16:28:12 volumio volumio[1265]: info: Received an update from plugin. extracting info from payload Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::servicePushState Feb 07 16:28:12 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:12 volumio volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 07 16:28:12 volumio volumio[1265]: verbose: CURRENT POSITION 0 Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState stateService play Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState currentStatus play Feb 07 16:28:12 volumio volumio[1265]: info: Received an update from plugin. extracting info from payload Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 155ms Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 155ms Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 141ms Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio sudo[3050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio sudo[3055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio sudo[3059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio sudo[3061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio sudo[3065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 07 16:28:12 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio sudo[3068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio sudo[3050]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: sendMpdCommand playlistinfo took 356 milliseconds Feb 07 16:28:12 volumio volumio[1265]: verbose: ControllerMpd::parseTrackInfo Feb 07 16:28:12 volumio volumio[1265]: info: ControllerMpd::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::servicePushState Feb 07 16:28:12 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Feb 07 16:28:12 volumio volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1980,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Serenity","artist":"Radio Paradise: Serenity (64k aac)","album":null,"uri":"http://stream.radioparadise.com/serenity","trackType":""} Feb 07 16:28:12 volumio volumio[1265]: verbose: CURRENT POSITION 0 Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState stateService play Feb 07 16:28:12 volumio sudo[3055]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::syncState currentStatus play Feb 07 16:28:12 volumio volumio[1265]: info: Received an update from plugin. extracting info from payload Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio sudo[3073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio sudo[3061]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: CoreStateMachine::pushState Feb 07 16:28:12 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Feb 07 16:28:12 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio sudo[3065]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: ------------------------------ 451ms Feb 07 16:28:12 volumio sudo[3068]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio sudo[3073]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio volumio[1265]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 16:28:12 volumio sudo[3077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio sudo[3079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 16:28:12 volumio sudo[3079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio sudo[3077]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:12 volumio sudo[3079]: pam_unix(sudo:session): session closed for user root Feb 07 16:28:12 volumio volumio[1265]: info: peppymeterbasic Daemon Started Feb 07 16:28:13 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:13 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:13 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:13 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:13 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:18 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:18 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:18 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:18 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:18 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:23 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:23 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:23 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:23 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:23 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:27 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Feb 07 16:28:29 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:29 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:29 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:29 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:29 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 16:28:33 volumio volumio[1265]: info: Retrieving Cloud Streaming UI Feb 07 16:28:33 volumio volumio[1265]: info: Getting Tidal Cloud Configuration Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 16:28:33 volumio volumio[1265]: info: Getting Qobuz Cloud Configuration Feb 07 16:28:33 volumio volumio[1265]: info: Asking plugin for UI Config Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 16:28:33 volumio volumio[1265]: info: Getting Spotify Cloud Configuration Feb 07 16:28:33 volumio volumio[1265]: info: Asking plugin for UI Config Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 16:28:33 volumio volumio[1265]: info: Saving Spotify Acccount Feb 07 16:28:33 volumio volumio[1265]: info: Got Tidal Cloud Configuration Feb 07 16:28:33 volumio volumio[1265]: info: Got it Feb 07 16:28:33 volumio volumio[1265]: info: Got it Feb 07 16:28:33 volumio volumio[1265]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 16:28:33 volumio volumio[1265]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 16:28:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 07 16:28:34 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:34 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:34 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:34 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:34 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:37 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 07 16:28:39 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:39 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:39 volumio volumio[1265]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 07 16:28:39 volumio volumio[1265]: info: Discovery: Restarting Browsing Feb 07 16:28:39 volumio volumio[1265]: info: Discovery: Restart already pending, ignoring duplicate call Feb 07 16:28:41 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 07 16:28:41 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 07 16:28:41 volumio volumio[1265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 16:28:41 volumio volumio[1265]: Error: getaddrinfo -3007 Feb 07 16:28:41 volumio volumio[1265]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 07 16:28:41 volumio volumio[1265]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 07 16:28:41 volumio volumio[1265]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 07 16:28:41 volumio volumio[1265]: code: -3007, Feb 07 16:28:41 volumio volumio[1265]: errno: -3007, Feb 07 16:28:41 volumio volumio[1265]: syscall: 'getaddrinfo' Feb 07 16:28:41 volumio volumio[1265]: } Feb 07 16:28:41 volumio volumio[1265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 16:28:42 volumio sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 16:27' Feb 07 16:28:42 volumio sudo[3241]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"