May 13 21:34:09 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:17 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 21:34:17 volumio volumio[1015]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 13 21:34:17 volumio volumio[1015]: info: Received Get System Version May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 21:34:17 volumio volumio[1015]: info: Received Get System Info May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:34:17 volumio volumio[1015]: info: Discovery: Getting this device information May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState May 13 21:34:17 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:34:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:34:24 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:34:24 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:34:24 volumio volumio[1015]: info: Discovery: Getting this device information May 13 21:34:24 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState May 13 21:34:24 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:34:24 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:34:25 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:25 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:28 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:38 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:38 volumio volumio[1015]: info: TuneIn handleBrowseUri: tunein/location May 13 21:34:38 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:40 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:40 volumio volumio[1015]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 May 13 21:34:40 volumio volumio[1015]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 May 13 21:34:41 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:44 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:44 volumio volumio[1015]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100385 May 13 21:34:44 volumio volumio[1015]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100385 May 13 21:34:44 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:44 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:48 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:48 volumio volumio[1015]: info: TuneIn handleBrowseUri: tunein/browse/?id=c474222 May 13 21:34:48 volumio volumio[1015]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=c474222 May 13 21:34:48 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:48 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:48 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:53 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:53 volumio volumio[1015]: info: TuneIn handleBrowseUri: tunein/browse/?id=c651202 May 13 21:34:53 volumio volumio[1015]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=c651202 May 13 21:34:53 volumio volumio[1015]: info: Preload queue cleared May 13 21:34:54 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:34:54 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:34:54 volumio volumio[1015]: info: Discovery: Getting this device information May 13 21:34:54 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState May 13 21:34:54 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:34:54 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:34:56 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 13 21:34:56 volumio volumio[1015]: info: TuneIn handleBrowseUri: tunein/browse/?id=c651208 May 13 21:34:56 volumio volumio[1015]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=c651208 May 13 21:34:57 volumio volumio[1015]: info: Preload queue cleared May 13 21:35:06 volumio volumio[1015]: info: Saving local item radio-favourites May 13 21:35:06 volumio volumio[1015]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s25548","favourite":false} May 13 21:35:07 volumio volumio[1015]: info: Preload queue cleared May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::ClearQueue May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::serviceStop May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::serviceStop May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::stop May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::saveQueue May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::addQueueItems May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::addQueueItems May 13 21:35:07 volumio volumio[1015]: info: Preload queue cleared May 13 21:35:07 volumio volumio[1015]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25548 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 13 21:35:07 volumio volumio[1015]: info: May 13 21:35:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand stop took 37 milliseconds May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:07 volumio volumio[1015]: info: May 13 21:35:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:07 volumio volumio[1015]: info: May 13 21:35:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:07 volumio volumio[1015]: info: May 13 21:35:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand status took 6 milliseconds May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand status took 5 milliseconds May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand status took 4 milliseconds May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 5 milliseconds May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds May 13 21:35:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 5 milliseconds May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: 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":"02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","artist":null,"album":null,"uri":"USB/Volumio_hdd_Dick/Beethoven, Ludwig van; John Eliot Gardiner, Orchestre Révolutionaire et Romantique - Missa Solemnis (Margiono, Robbin, Kendall, Miles)/02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","trackType":"wav"} May 13 21:35:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: No code May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: 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":"02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","artist":null,"album":null,"uri":"USB/Volumio_hdd_Dick/Beethoven, Ludwig van; John Eliot Gardiner, Orchestre Révolutionaire et Romantique - Missa Solemnis (Margiono, Robbin, Kendall, Miles)/02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","trackType":"wav"} May 13 21:35:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: No code May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: 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":"02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","artist":null,"album":null,"uri":"USB/Volumio_hdd_Dick/Beethoven, Ludwig van; John Eliot Gardiner, Orchestre Révolutionaire et Romantique - Missa Solemnis (Margiono, Robbin, Kendall, Miles)/02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","trackType":"wav"} May 13 21:35:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: No code May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: 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":"02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","artist":null,"album":null,"uri":"USB/Volumio_hdd_Dick/Beethoven, Ludwig van; John Eliot Gardiner, Orchestre Révolutionaire et Romantique - Missa Solemnis (Margiono, Robbin, Kendall, Miles)/02. Beethoven - Missa Solemnis in D major, op. 123- 2. Gloria - Allegro vivace (Missa Solemnis).wav","trackType":"wav"} May 13 21:35:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: No code May 13 21:35:07 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 May 13 21:35:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:07 volumio volumio[1015]: info: ------------------------------ 147ms May 13 21:35:07 volumio volumio[1015]: info: ------------------------------ 146ms May 13 21:35:07 volumio volumio[1015]: info: ------------------------------ 146ms May 13 21:35:07 volumio volumio[1015]: info: ------------------------------ 144ms May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::saveQueue May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::play index 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::stop May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::play index undefined May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: info: [1747164908249] ControllerWebradio::clearAddPlayTrack May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand stop took 4 milliseconds May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand clear took 0 milliseconds May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.omroep.nl/radio4-bb-mp3" May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: error: updateQueue error: null May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 4ms May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.omroep.nl/radio4-bb-mp3" May 13 21:35:08 volumio volumio[1015]: error: updateQueue error: null May 13 21:35:08 volumio volumio[1015]: error: updateQueue error: null May 13 21:35:08 volumio volumio[1015]: error: updateQueue error: null May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 28ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 27ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 28ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand add "http://icecast.omroep.nl/radio4-bb-mp3" took 2 milliseconds May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 5ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand play took 4 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 3ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 2ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 2ms May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 13 21:35:08 volumio volumio[1015]: info: FusionDsp - ---- read samplerate from file: 48000 May 13 21:35:08 volumio volumio[1015]: info: camilladsp stopping service pid 1648... May 13 21:35:08 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 May 13 21:35:08 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d14560 00000000 00000000 0e000000 04028000 May 13 21:35:08 volumio volumio[1015]: grep: /proc/1648/cmdline: binary file matches May 13 21:35:08 volumio volumio[1015]: grep: /proc/1648/cmdline: binary file matches May 13 21:35:08 volumio volumio[1015]: grep: /proc/1648/cmdline: binary file matches May 13 21:35:08 volumio volumio[1015]: info: camilladsp service terminated, instance 1 May 13 21:35:08 volumio volumio[1015]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 13 21:35:08 volumio volumio[1015]: info: camilladsp service started and running in background, instance 1 May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 352 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 351 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 350 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 349 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 13 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 14ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 12 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 13 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 12 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 11 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 11 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 10 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 10 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 9 milliseconds May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 391ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 431ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 430ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 429ms May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces system playlist update May 13 21:35:08 volumio volumio[1015]: info: Ignoring MPD Status Update May 13 21:35:08 volumio volumio[1015]: info: May 13 21:35:08 volumio volumio[1015]: ---------------------------- MPD announces state update: player May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::getState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 84ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 84 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 82 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 81 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 81 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 81 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 80 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 17ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 17 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 17ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 15 milliseconds May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 15ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand status took 15 milliseconds May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseState May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 226ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 226ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 221ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 221ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 221ms May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 155 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 153 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 154 milliseconds May 13 21:35:08 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 153 milliseconds May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ControllerMpd::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::servicePushState May 13 21:35:08 volumio volumio[1015]: info: CorePlayQueue::getTrack 0 May 13 21:35:08 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mahler, Gustav - Symfonie nr.5 in cis kl.t. - compleet","artist":"NPO Klassiek","album":null,"uri":"http://icecast.omroep.nl/radio4-bb-mp3","trackType":""} May 13 21:35:08 volumio volumio[1015]: verbose: CURRENT POSITION 0 May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play May 13 21:35:08 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: CoreStateMachine::pushState May 13 21:35:08 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 315ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 247ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 246ms May 13 21:35:08 volumio volumio[1015]: info: ------------------------------ 245ms May 13 21:35:24 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 21:35:24 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 21:35:24 volumio volumio[1015]: info: Discovery: Getting this device information May 13 21:35:24 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState May 13 21:35:24 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 21:35:29 volumio volumio[1015]: Searching plugin music_service/webradio May 13 21:35:29 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 13 21:35:29 volumio volumio[1015]: Use XMLElement.setAttribute instead May 13 21:35:29 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 21:35:29 volumio volumio[1015]: TypeError: children[i].attr(...).value is not a function May 13 21:35:29 volumio volumio[1015]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 13 21:35:29 volumio volumio[1015]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 13 21:35:29 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 21:35:30 volumio sudo[7849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-13 21:34' May 13 21:35:30 volumio sudo[7849]: 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="10fe97f56e977d23bd5c31bb51c11a06f700d721" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 10 21:45:05 UTC 2025" VOLUMIO_VERSION="0.065" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="9d0e0ad86d94167193da8e63f62f5e4e"