Jul 02 22:20:00 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "https://discoclub.stream.laut.fm/discoclub?ref=radiodns" Jul 02 22:20:00 volumio volumio[1060]: error: updateQueue error: null Jul 02 22:20:00 volumio volumio[1060]: error: updateQueue error: null Jul 02 22:20:00 volumio volumio[1060]: info: Jul 02 22:20:00 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:00 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:00 volumio volumio[1060]: info: ------------------------------ 706ms Jul 02 22:20:00 volumio volumio[1060]: info: ------------------------------ 705ms Jul 02 22:20:00 volumio volumio[1060]: info: sendMpdCommand add "https://discoclub.stream.laut.fm/discoclub?ref=radiodns" took 4 milliseconds Jul 02 22:20:00 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 22:20:00 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Jul 02 22:20:00 volumio volumio[1060]: info: Jul 02 22:20:00 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:00 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:00 volumio volumio[1060]: info: Jul 02 22:20:00 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:00 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:00 volumio volumio[1060]: info: ------------------------------ 4ms Jul 02 22:20:00 volumio volumio[1060]: info: sendMpdCommand play took 4 milliseconds Jul 02 22:20:00 volumio volumio[1060]: info: ------------------------------ 4ms Jul 02 22:20:00 volumio volumio[1060]: info: ------------------------------ 3ms Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 18 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:01 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:01 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 56 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 51 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 51 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 36 milliseconds Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:01 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:01 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:01 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Jul 02 22:20:01 volumio volumio[1060]: info: ------------------------------ 131ms Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:01 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:01 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:01 volumio volumio[1060]: info: Jul 02 22:20:01 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 101 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ------------------------------ 109ms Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 91 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ------------------------------ 103ms Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 87 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 74 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 73 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 64 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ------------------------------ 35ms Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 29 milliseconds Jul 02 22:20:01 volumio volumio[1060]: info: ------------------------------ 31ms Jul 02 22:20:01 volumio volumio[1060]: info: sendMpdCommand status took 22 milliseconds Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:01 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:01 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:01 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:01 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:01 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:01 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:01 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:01 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:01 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:01 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:01 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:01 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:01 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:01 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:01 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:01 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:01 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:01 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 503ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 497ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 494ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 473ms Jul 02 22:20:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 346 milliseconds Jul 02 22:20:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 345 milliseconds Jul 02 22:20:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 345 milliseconds Jul 02 22:20:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 340 milliseconds Jul 02 22:20:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 334 milliseconds Jul 02 22:20:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Delegation - It's Your Turn (12-Inch) 1983","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 856ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 847ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 844ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 780ms Jul 02 22:20:02 volumio volumio[1060]: info: ------------------------------ 770ms Jul 02 22:20:06 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 02 22:20:06 volumio dbus-daemon[567]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.28' (uid=0 pid=2170 comm="timedatectl show --property=NTPSynchronized --valu") Jul 02 22:20:06 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 02 22:20:07 volumio dbus-daemon[567]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 02 22:20:07 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 02 22:20:07 volumio setdatetime-helper.sh[2169]: Time is not synchronized. Attempting to sync... Jul 02 22:20:07 volumio sudo[2180]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 02 Jul 2025 20:20:07 GMT#015' Jul 02 22:20:07 volumio sudo[2180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 02 22:20:07 volumio setdatetime-helper.sh[2181]: Wed Jul 2 22:20:07 CEST 2025 Jul 02 22:20:07 volumio sudo[2180]: pam_unix(sudo:session): session closed for user root Jul 02 22:20:07 volumio setdatetime-helper.sh[2169]: Time synchronized successfully. Jul 02 22:20:07 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 02 22:20:07 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 02 22:20:07 volumio systemd-journald[333]: Time jumped backwards, rotating. Jul 02 22:20:31 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 22:20:31 volumio volumio[1060]: info: TuneIn handleBrowseUri: tunein/location Jul 02 22:20:32 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:33 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 22:20:33 volumio volumio[1060]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 Jul 02 22:20:33 volumio volumio[1060]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 Jul 02 22:20:33 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:36 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 02 22:20:37 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 22:20:37 volumio volumio[1060]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100346 Jul 02 22:20:37 volumio volumio[1060]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100346 Jul 02 22:20:37 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:37 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:39 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 02 22:20:39 volumio volumio[1060]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101822 Jul 02 22:20:39 volumio volumio[1060]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101822 Jul 02 22:20:40 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:40 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:42 volumio volumio[1060]: info: Jul 02 22:20:42 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:42 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:42 volumio volumio[1060]: info: Jul 02 22:20:42 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:42 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:42 volumio volumio[1060]: info: Jul 02 22:20:42 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:42 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:42 volumio volumio[1060]: info: Jul 02 22:20:42 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:42 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:42 volumio volumio[1060]: info: Jul 02 22:20:42 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:42 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:42 volumio volumio[1060]: info: Jul 02 22:20:42 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:42 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:42 volumio volumio[1060]: info: ------------------------------ 32ms Jul 02 22:20:42 volumio volumio[1060]: info: sendMpdCommand status took 23 milliseconds Jul 02 22:20:42 volumio volumio[1060]: info: ------------------------------ 24ms Jul 02 22:20:42 volumio volumio[1060]: info: sendMpdCommand status took 14 milliseconds Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:42 volumio volumio[1060]: info: ------------------------------ 22ms Jul 02 22:20:42 volumio volumio[1060]: info: sendMpdCommand status took 19 milliseconds Jul 02 22:20:42 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 02 22:20:42 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:42 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:42 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:42 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Sister Sledge - He's The Greatest Dancer","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:42 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:42 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:42 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:42 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:42 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Sister Sledge - He's The Greatest Dancer","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:42 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:42 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:42 volumio volumio[1060]: info: ------------------------------ 261ms Jul 02 22:20:42 volumio volumio[1060]: info: ------------------------------ 253ms Jul 02 22:20:42 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 214 milliseconds Jul 02 22:20:42 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:42 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:42 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:42 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Sister Sledge - He's The Greatest Dancer","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:42 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:20:42 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:42 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:42 volumio volumio[1060]: info: ------------------------------ 298ms Jul 02 22:20:51 volumio volumio[1060]: info: Saving local item radio-favourites Jul 02 22:20:51 volumio volumio[1060]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s96945","favourite":false} Jul 02 22:20:57 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::ClearQueue Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::stPlaybackTimer Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::updateTrackBlock Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrackBlock Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::serviceStop Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::serviceStop Jul 02 22:20:57 volumio volumio[1060]: info: [1751487657549] ControllerWebradio::stop Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::clearPlayQueue Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::saveQueue Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushQueue Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::addQueueItems Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::addQueueItems Jul 02 22:20:57 volumio volumio[1060]: info: Preload queue cleared Jul 02 22:20:57 volumio volumio[1060]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s309907 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 02 22:20:57 volumio volumio[1060]: info: Jul 02 22:20:57 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand stop took 111 milliseconds Jul 02 22:20:57 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:57 volumio volumio[1060]: info: Jul 02 22:20:57 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:57 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:57 volumio volumio[1060]: info: Jul 02 22:20:57 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:20:57 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand status took 9 milliseconds Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand status took 9 milliseconds Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand status took 4 milliseconds Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 02 22:20:57 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:57 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:20:57 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sister Sledge - He's The Greatest Dancer","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:57 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: No code Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sister Sledge - He's The Greatest Dancer","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:57 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: No code Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sister Sledge - He's The Greatest Dancer","artist":"Discoclub","album":null,"uri":"https://discoclub.stream.laut.fm/discoclub?ref=radiodns","trackType":""} Jul 02 22:20:57 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::syncState stateService stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: No code Jul 02 22:20:57 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:20:57 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:57 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:20:57 volumio volumio[1060]: info: ------------------------------ 281ms Jul 02 22:20:57 volumio volumio[1060]: info: ------------------------------ 273ms Jul 02 22:20:57 volumio volumio[1060]: info: ------------------------------ 282ms Jul 02 22:20:58 volumio volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 02 22:20:58 volumio volumio[1060]: info: CoreCommandRouter::volumioPushQueue Jul 02 22:20:58 volumio volumio[1060]: info: CorePlayQueue::saveQueue Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::updateTrackBlock Jul 02 22:20:58 volumio volumio[1060]: info: CorePlayQueue::getTrackBlock Jul 02 22:20:58 volumio volumio[1060]: info: CoreCommandRouter::volumioPlay Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::play index 0 Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::stop Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::play index undefined Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 22:20:58 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:58 volumio volumio[1060]: info: CoreStateMachine::startPlaybackTimer Jul 02 22:20:58 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:20:58 volumio volumio[1060]: info: [1751487658673] ControllerWebradio::clearAddPlayTrack Jul 02 22:20:58 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 22:20:58 volumio volumio[1060]: info: sendMpdCommand stop took 2 milliseconds Jul 02 22:20:58 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 22:20:58 volumio volumio[1060]: info: Jul 02 22:20:58 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:58 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:58 volumio volumio[1060]: info: sendMpdCommand clear took 1 milliseconds Jul 02 22:20:58 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand load "https://stream.klassikradio.de/beats-national/mp3-192/tunein" Jul 02 22:20:58 volumio volumio[1060]: info: Jul 02 22:20:58 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:58 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:58 volumio volumio[1060]: info: Jul 02 22:20:58 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:20:58 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:20:58 volumio volumio[1060]: error: updateQueue error: null Jul 02 22:20:58 volumio volumio[1060]: info: ------------------------------ 24ms Jul 02 22:21:00 volumio volumio[1060]: error: updateQueue error: null Jul 02 22:21:00 volumio volumio[1060]: error: updateQueue error: null Jul 02 22:21:00 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "https://stream.klassikradio.de/beats-national/mp3-192/tunein" Jul 02 22:21:00 volumio volumio[1060]: info: ------------------------------ 1761ms Jul 02 22:21:00 volumio volumio[1060]: info: ------------------------------ 1760ms Jul 02 22:21:00 volumio volumio[1060]: info: Jul 02 22:21:00 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:00 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:00 volumio volumio[1060]: info: sendMpdCommand add "https://stream.klassikradio.de/beats-national/mp3-192/tunein" took 1 milliseconds Jul 02 22:21:00 volumio volumio[1060]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 22:21:00 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Jul 02 22:21:00 volumio volumio[1060]: info: Jul 02 22:21:00 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:00 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:00 volumio volumio[1060]: info: Jul 02 22:21:00 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:00 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:00 volumio volumio[1060]: info: ------------------------------ 14ms Jul 02 22:21:00 volumio volumio[1060]: info: sendMpdCommand play took 9 milliseconds Jul 02 22:21:00 volumio volumio[1060]: info: ------------------------------ 5ms Jul 02 22:21:00 volumio volumio[1060]: info: ------------------------------ 2ms Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 2 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:02 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 54 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 54 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 53 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 11 milliseconds Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 61ms Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:02 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:02 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:02 volumio volumio[1060]: info: Jul 02 22:21:02 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 51 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 56ms Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 57 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 61 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 58ms Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 58 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 58ms Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand status took 60 milliseconds Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 377ms Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 379ms Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 382ms Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 347ms Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 263 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 267 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 259 milliseconds Jul 02 22:21:02 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 259 milliseconds Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:02 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:02 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Beats Radio national","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:02 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:02 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:02 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 704ms Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 696ms Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 691ms Jul 02 22:21:02 volumio volumio[1060]: info: ------------------------------ 690ms Jul 02 22:21:04 volumio volumio[1060]: info: Saving local item radio-favourites Jul 02 22:21:04 volumio volumio[1060]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s309907","favourite":false} Jul 02 22:21:05 volumio volumio[1060]: info: Jul 02 22:21:05 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:05 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:05 volumio volumio[1060]: info: Jul 02 22:21:05 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:05 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:05 volumio volumio[1060]: info: Jul 02 22:21:05 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:05 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:05 volumio volumio[1060]: info: Jul 02 22:21:05 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:05 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:05 volumio volumio[1060]: info: Jul 02 22:21:05 volumio volumio[1060]: ---------------------------- MPD announces system playlist update Jul 02 22:21:05 volumio volumio[1060]: info: Ignoring MPD Status Update Jul 02 22:21:05 volumio volumio[1060]: info: Jul 02 22:21:05 volumio volumio[1060]: ---------------------------- MPD announces state update: player Jul 02 22:21:05 volumio volumio[1060]: info: ControllerMpd::getState Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Jul 02 22:21:05 volumio volumio[1060]: info: ------------------------------ 9ms Jul 02 22:21:05 volumio volumio[1060]: info: sendMpdCommand status took 11 milliseconds Jul 02 22:21:05 volumio volumio[1060]: info: ------------------------------ 8ms Jul 02 22:21:05 volumio volumio[1060]: info: sendMpdCommand status took 5 milliseconds Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:05 volumio volumio[1060]: info: ------------------------------ 11ms Jul 02 22:21:05 volumio volumio[1060]: info: sendMpdCommand status took 10 milliseconds Jul 02 22:21:05 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 02 22:21:05 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::parseState Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:05 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:05 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:05 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bumblb - One Day","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:05 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:05 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:05 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:05 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:05 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bumblb - One Day","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:05 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:05 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:05 volumio volumio[1060]: info: ------------------------------ 163ms Jul 02 22:21:05 volumio volumio[1060]: info: ------------------------------ 161ms Jul 02 22:21:05 volumio volumio[1060]: info: sendMpdCommand playlistinfo took 142 milliseconds Jul 02 22:21:05 volumio volumio[1060]: verbose: ControllerMpd::parseTrackInfo Jul 02 22:21:05 volumio volumio[1060]: info: ControllerMpd::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::servicePushState Jul 02 22:21:05 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 02 22:21:05 volumio volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bumblb - One Day","artist":"Beats Radio national","album":null,"uri":"https://stream.klassikradio.de/beats-national/mp3-192/tunein","trackType":""} Jul 02 22:21:05 volumio volumio[1060]: verbose: CURRENT POSITION 0 Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::syncState stateService play Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::syncState currentStatus play Jul 02 22:21:05 volumio volumio[1060]: info: Received an update from plugin. extracting info from payload Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreStateMachine::pushState Jul 02 22:21:05 volumio volumio[1060]: info: CoreCommandRouter::volumioPushState Jul 02 22:21:05 volumio volumio[1060]: info: ------------------------------ 218ms Jul 02 22:21:19 volumio volumio[1060]: info: Saving local item radio-favourites Jul 02 22:21:19 volumio volumio[1060]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s25529","favourite":false} Jul 02 22:21:39 volumio volumio[1060]: info: Saving local item radio-favourites Jul 02 22:21:39 volumio volumio[1060]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s241894","favourite":false} Jul 02 22:21:39 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 02 22:21:39 volumio dbus-daemon[567]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.30' (uid=0 pid=2516 comm="timedatectl show --property=NTPSynchronized --valu") Jul 02 22:21:39 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 02 22:21:39 volumio dbus-daemon[567]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 02 22:21:39 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 02 22:21:39 volumio setdatetime-helper.sh[2515]: Time is not synchronized. Attempting to sync... Jul 02 22:21:44 volumio sudo[2534]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 02 Jul 2025 20:21:44 GMT#015' Jul 02 22:21:44 volumio sudo[2534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 02 22:21:44 volumio setdatetime-helper.sh[2535]: Wed Jul 2 22:21:44 CEST 2025 Jul 02 22:21:44 volumio setdatetime-helper.sh[2515]: Time synchronized successfully. Jul 02 22:21:44 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 02 22:21:44 volumio sudo[2534]: pam_unix(sudo:session): session closed for user root Jul 02 22:21:44 volumio systemd-journald[333]: Time jumped backwards, rotating. Jul 02 22:21:44 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 02 22:21:49 volumio nmbd[974]: [2025/07/02 22:21:49.116566, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 02 22:21:49 volumio nmbd[974]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.107 for name WORKGROUP<1d>. Jul 02 22:21:49 volumio nmbd[974]: This response was from IP 192.168.10.5, reporting an IP address of 192.168.10.5. Jul 02 22:21:52 volumio volumio[1060]: Searching plugin music_service/webradio Jul 02 22:21:52 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 02 22:21:52 volumio volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 22:21:52 volumio volumio[1060]: TypeError: Cannot read properties of undefined (reading 'length') Jul 02 22:21:52 volumio volumio[1060]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jul 02 22:21:52 volumio volumio[1060]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jul 02 22:21:52 volumio volumio[1060]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jul 02 22:21:52 volumio volumio[1060]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 02 22:21:52 volumio volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 22:21:52 volumio sudo[2584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-02 22:20' Jul 02 22:21:52 volumio sudo[2584]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"