May 18 11:22:28 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 11:22:28 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 11:22:28 volumio volumio[1028]: info: Discovery: Getting this device information May 18 11:22:28 volumio volumio[1028]: info: CoreCommandRouter::volumioGetState May 18 11:22:28 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 11:22:29 volumio volumio[1028]: verbose: New Socket.io Connection to 192.168.2.2:3000 from 192.168.2.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:39 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:39 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:39 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 5ms May 18 11:22:39 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 4ms May 18 11:22:39 volumio volumio[1028]: info: sendMpdCommand status took 3 milliseconds May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:39 volumio volumio[1028]: info: sendMpdCommand status took 2 milliseconds May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 11:22:39 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::play index undefined May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::startPlaybackTimer May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: [1747560159637] ControllerWebradio::clearAddPlayTrack May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand stop May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 23ms May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 23ms May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 21ms May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:39 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 25ms May 18 11:22:39 volumio volumio[1028]: info: sendMpdCommand status took 23 milliseconds May 18 11:22:39 volumio volumio[1028]: info: sendMpdCommand stop took 5 milliseconds May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clear May 18 11:22:39 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:39 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:39 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:39 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 28ms May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: sendMpdCommand clear took 9 milliseconds May 18 11:22:39 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand load "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: info: May 18 11:22:39 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:39 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:39 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:39 volumio volumio[1028]: info: ------------------------------ 4ms May 18 11:22:39 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 May 18 11:22:39 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d146c0 00000000 00000000 0e000000 04028001 May 18 11:22:40 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:40 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:40 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand add "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 565ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 565ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 564ms May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand add "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" took 0 milliseconds May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand play May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:40 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:40 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:40 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:40 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand play took 4 milliseconds May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 2ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 2ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 1ms May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 11:22:40 volumio volumio[1028]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 11:22:40 volumio volumio[1028]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 11:22:40 volumio volumio[1028]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 11:22:40 volumio volumio[1028]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 11:22:40 volumio volumio[1028]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 17 milliseconds May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: May 18 11:22:40 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 5 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 5 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 5 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 5 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 3 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand status took 2 milliseconds May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":320,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 38ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 43ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 42ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 42ms May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 19 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:40 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1558,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1558,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1558,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:40 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:40 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1558,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:40 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:40 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:40 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 43ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 41ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 40ms May 18 11:22:40 volumio volumio[1028]: info: ------------------------------ 39ms May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 6ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 4ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 3 milliseconds May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 2ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 2 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::play index undefined May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::startPlaybackTimer May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: [1747560167495] ControllerWebradio::clearAddPlayTrack May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand stop May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 17ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 15ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 15ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 14ms May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand stop took 4 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clear May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand clear took 6 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand load "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand add "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 78ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 78ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 77ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand add "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" took 1 milliseconds May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand play May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand play took 1 milliseconds May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:47 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 2ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 2ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 1ms May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 11:22:47 volumio volumio[1028]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 11:22:47 volumio volumio[1028]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 6 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: May 18 11:22:47 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":412,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":550,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":550,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 19ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 26ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 25ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 25ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 20 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 19 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand status took 20 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 29ms May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 12 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 13 milliseconds May 18 11:22:47 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 13 milliseconds May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:47 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:47 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:47 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:47 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:47 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 46ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 45ms May 18 11:22:47 volumio volumio[1028]: info: ------------------------------ 46ms May 18 11:22:48 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 May 18 11:22:48 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d18ba0 00000000 00000000 0e000000 04028001 May 18 11:22:50 volumio volumio[1028]: info: CoreCommandRouter::volumioGetState May 18 11:22:54 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 18 11:22:54 volumio volumio[1028]: info: Preload queue cleared May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 5ms May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 4ms May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 3 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::play index undefined May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::startPlaybackTimer May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: [1747560175354] ControllerWebradio::clearAddPlayTrack May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand stop May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 14ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 12ms May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 15ms May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 14 milliseconds May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 14ms May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 13 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand stop took 4 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clear May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 23ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 22ms May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand clear took 12 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand load "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 78ms May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand add "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: error: updateQueue error: null May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 77ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 77ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 77ms May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand add "https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA" took 1 milliseconds May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand play May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces system playlist update May 18 11:22:55 volumio volumio[1028]: info: Ignoring MPD Status Update May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand play took 4 milliseconds May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 4ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 3ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 2ms May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 11:22:55 volumio volumio[1028]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 11:22:55 volumio volumio[1028]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 7 milliseconds May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 7 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 7 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 6 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 2 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 22ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 28ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 27ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 26ms May 18 11:22:55 volumio volumio[1028]: info: May 18 11:22:55 volumio volumio[1028]: ---------------------------- MPD announces state update: player May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::getState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 20 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 19 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand status took 2 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseState May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"40 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 33ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 30ms May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:55 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ControllerMpd::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::servicePushState May 18 11:22:55 volumio volumio[1028]: info: CorePlayQueue::getTrack 0 May 18 11:22:55 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":8,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"79386.mp3?access_key=Y3TrkXn8JM-VuA","artist":null,"album":null,"uri":"https://kerkdienstgemist.nl/streams/79386.mp3?access_key=Y3TrkXn8JM-VuA","trackType":""} May 18 11:22:55 volumio volumio[1028]: verbose: CURRENT POSITION 0 May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play May 18 11:22:55 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: CoreStateMachine::pushState May 18 11:22:55 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 46ms May 18 11:22:55 volumio volumio[1028]: info: ------------------------------ 28ms May 18 11:22:56 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 May 18 11:22:56 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d1e080 00000000 00000000 0e000000 04028001 May 18 11:23:00 volumio volumio[1028]: Searching plugin music_service/webradio May 18 11:23:00 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 18 11:23:01 volumio volumio[1028]: Use XMLElement.setAttribute instead May 18 11:23:01 volumio volumio[1028]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 11:23:01 volumio volumio[1028]: TypeError: children[i].attr(...).value is not a function May 18 11:23:01 volumio volumio[1028]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 18 11:23:01 volumio volumio[1028]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 18 11:23:01 volumio volumio[1028]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 11:23:01 volumio sudo[11707]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-18 11:22' May 18 11:23:01 volumio sudo[11707]: 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"