-- Logs begin at Mon 2025-02-24 00:13:17 CST, end at Mon 2025-02-24 00:17:49 CST. -- Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 8 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 4 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 3 milliseconds Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: Feb 24 00:16:02 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 10 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 5 milliseconds Feb 24 00:16:02 volumio volumio[918]: info: sendMpdCommand status took 4 milliseconds Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:02 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:02 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus stop Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:02 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:02 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:02 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:02 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:02 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:02 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:02 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:02 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:02 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:02 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:02 volumio volumio[918]: info: ------------------------------ 49ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 85ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 83ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 83ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 69 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 69 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: 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":"antenne","artist":"(#1 - 6209/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 153ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 148ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 148ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 147ms Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 8ms Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 8 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 7ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 7 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 6ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 6ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 5 milliseconds Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:03 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:03 volumio volumio[918]: info: Feb 24 00:16:03 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 13ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 11 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 13ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 10 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 7ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 6ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 4 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 5ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand status took 4 milliseconds Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 77ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 75ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 74ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 73ms Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 57 milliseconds Feb 24 00:16:03 volumio volumio[918]: info: sendMpdCommand playlistinfo took 57 milliseconds Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:03 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:03 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":724,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:03 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:03 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 137ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 136ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 130ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 129ms Feb 24 00:16:03 volumio volumio[918]: info: ------------------------------ 127ms Feb 24 00:16:03 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:03 volumio sudo[2052]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Feb 24 00:16:03 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:04 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:04 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:04 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:04 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:04 volumio volumio[918]: info: Feb 24 00:16:04 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 7ms Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 7ms Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand status took 7 milliseconds Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 6ms Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand status took 5 milliseconds Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 5ms Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand status took 4 milliseconds Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 00:16:04 volumio volumio[918]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:04 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:04 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:04 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:04 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:04 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:04 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:04 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:04 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:04 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:04 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:04 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:04 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:04 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:04 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:04 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:04 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:04 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:04 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 64ms Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 64ms Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 63ms Feb 24 00:16:04 volumio volumio[918]: info: ------------------------------ 62ms Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:05 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:05 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 9ms Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand status took 8 milliseconds Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 8ms Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand status took 2 milliseconds Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:05 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:05 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:05 volumio volumio[918]: info: Feb 24 00:16:05 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 6ms Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand status took 3 milliseconds Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 4ms Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand status took 2 milliseconds Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:05 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:05 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2492,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:05 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:05 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:05 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:05 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2492,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:05 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:05 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 64ms Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 62ms Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand playlistinfo took 44 milliseconds Feb 24 00:16:05 volumio volumio[918]: info: sendMpdCommand playlistinfo took 44 milliseconds Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:05 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:05 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:05 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2492,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:05 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:05 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:05 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:05 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2492,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:05 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:05 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:05 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 85ms Feb 24 00:16:05 volumio volumio[918]: info: ------------------------------ 84ms Feb 24 00:16:06 volumio volumio[918]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Feb 24 00:16:06 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:06 volumio volumio[918]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ANTENNE%20BAYERN/e23966ba-bcbd-4da5-8c73-fbac01c9b032.jpg' Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:06 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:06 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:06 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:06 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:06 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:06 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces system playlist update Feb 24 00:16:06 volumio volumio[918]: info: Ignoring MPD Status Update Feb 24 00:16:06 volumio volumio[918]: info: Feb 24 00:16:06 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:06 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:06 volumio volumio[918]: info: ------------------------------ 7ms Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:06 volumio volumio[918]: info: ------------------------------ 7ms Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:06 volumio volumio[918]: info: ------------------------------ 5ms Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand status took 4 milliseconds Feb 24 00:16:06 volumio volumio[918]: info: ------------------------------ 4ms Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand status took 6 milliseconds Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 00:16:06 volumio volumio[918]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:06 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:06 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:06 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:06 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:06 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4254,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:06 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:06 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:06 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:06 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:06 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:06 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:06 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:06 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:07 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:07 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4254,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:07 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:07 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:07 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:07 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4254,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:07 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:07 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: ------------------------------ 69ms Feb 24 00:16:07 volumio volumio[918]: info: ------------------------------ 67ms Feb 24 00:16:07 volumio volumio[918]: info: ------------------------------ 67ms Feb 24 00:16:07 volumio volumio[918]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 24 00:16:07 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:07 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:07 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:07 volumio volumio[918]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4254,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Michael Jackson - Man in the mirror","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Feb 24 00:16:07 volumio volumio[918]: verbose: CURRENT POSITION 0 Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::syncState stateService play Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::syncState currentStatus play Feb 24 00:16:07 volumio volumio[918]: info: Received an update from plugin. extracting info from payload Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:07 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:07 volumio volumio[918]: info: ------------------------------ 87ms Feb 24 00:16:10 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::volumioStop Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::stop Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::stPlaybackTimer Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::updateTrackBlock Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrackBlock Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::serviceStop Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::serviceStop Feb 24 00:16:11 volumio volumio[918]: info: [1740327371014] ControllerWebradio::stop Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 00:16:11 volumio volumio[918]: info: Feb 24 00:16:11 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand stop took 26 milliseconds Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand status took 1 milliseconds Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:11 volumio volumio[918]: info: Feb 24 00:16:11 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:11 volumio volumio[918]: info: Feb 24 00:16:11 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:11 volumio volumio[918]: info: Feb 24 00:16:11 volumio volumio[918]: ---------------------------- MPD announces state update: player Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::getState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand status Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand status took 3 milliseconds Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand status took 2 milliseconds Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand status took 1 milliseconds Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::parseState Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 00:16:11 volumio volumio[918]: info: ------------------------------ 21ms Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 24 00:16:11 volumio volumio[918]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 24 00:16:11 volumio volumio[918]: verbose: ControllerMpd::parseTrackInfo Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:11 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 00:16:11 volumio volumio[918]: info: ControllerMpd::pushState Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::servicePushState Feb 24 00:16:11 volumio volumio[918]: info: CoreStateMachine::pushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: CoreCommandRouter::volumioPushState Feb 24 00:16:11 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:11 volumio volumio[918]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 00:16:11 volumio volumio[918]: info: ------------------------------ 55ms Feb 24 00:16:11 volumio volumio[918]: info: ------------------------------ 54ms Feb 24 00:16:22 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:22 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:33 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:33 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:44 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:44 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:16:55 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:16:55 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:17:06 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:17:06 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:17:17 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:17:17 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:17:28 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:17:28 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:17:39 volumio volumio[918]: info: CoreCommandRouter::volumioGetState Feb 24 00:17:39 volumio volumio[918]: info: CorePlayQueue::getTrack 0 Feb 24 00:17:49 volumio volumio[918]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 00:17:49 volumio volumio[918]: Error: connect ETIMEDOUT 202.160.128.210:80 Feb 24 00:17:49 volumio volumio[918]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 24 00:17:49 volumio volumio[918]: errno: -110, Feb 24 00:17:49 volumio volumio[918]: code: 'ETIMEDOUT', Feb 24 00:17:49 volumio volumio[918]: syscall: 'connect', Feb 24 00:17:49 volumio volumio[918]: address: '202.160.128.210', Feb 24 00:17:49 volumio volumio[918]: port: 80 Feb 24 00:17:49 volumio volumio[918]: } Feb 24 00:17:49 volumio volumio[918]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 00:17:49 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 00:16 Feb 24 00:17:49 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"