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"