-- Logs begin at Mon 2025-06-16 18:36:02 CEST, end at Mon 2025-06-16 18:53:36 CEST. -- Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPlay Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::play index undefined Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::clearAddPlayTracks NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand stop Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand stop took 1 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand clear Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces system playlist update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Ignoring MPD Status Update Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand clear took 1 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand add "NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3" Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces system playlist update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Ignoring MPD Status Update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces system playlist update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Ignoring MPD Status Update Jun 16 18:52:06 volumio5bern volumio[10197]: error: updateQueue error: null Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces system playlist update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Ignoring MPD Status Update Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 3ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand add "NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3" took 2 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 1ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 2ms Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces system playlist update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Ignoring MPD Status Update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces system playlist update Jun 16 18:52:06 volumio5bern volumio[10197]: info: Ignoring MPD Status Update Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 2ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand play took 1 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 1ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 1ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand status took 6 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand status took 6 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand status took 5 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus stop Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 11ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:06 volumio5bern volumio[10197]: info: Jun 16 18:52:06 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand status took 5 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:06 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:06 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 33ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 34ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:06 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:06 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":502,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:06 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:06 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 50ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 46ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: ------------------------------ 45ms Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: stop Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: ON Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:52:06 volumio5bern volumio[10197]: info: CoreStateMachine::getQueue Jun 16 18:52:06 volumio5bern volumio[10197]: info: CorePlayQueue::getQueue Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::servicePause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePause Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::pause Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: info: Jun 16 18:52:08 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:08 volumio5bern volumio[10197]: info: Jun 16 18:52:08 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:08 volumio5bern volumio[10197]: info: Jun 16 18:52:08 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3748,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3748,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:08 volumio5bern volumio[10197]: info: ------------------------------ 13ms Jun 16 18:52:08 volumio5bern volumio[10197]: info: ------------------------------ 13ms Jun 16 18:52:08 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:08 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3748,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:08 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:08 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:09 volumio5bern volumio[10197]: info: ------------------------------ 18ms Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:09 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPlay Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::play index undefined Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::resume Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:52:10 volumio5bern volumio[10197]: info: Jun 16 18:52:10 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand play took 10 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:10 volumio5bern volumio[10197]: info: Jun 16 18:52:10 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:10 volumio5bern volumio[10197]: info: Jun 16 18:52:10 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4548,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4594,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:10 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: ------------------------------ 16ms Jun 16 18:52:10 volumio5bern volumio[10197]: info: ------------------------------ 17ms Jun 16 18:52:10 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:10 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4617,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:10 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:10 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:10 volumio5bern volumio[10197]: info: ------------------------------ 24ms Jun 16 18:52:10 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:52:10 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: ON Jun 16 18:52:10 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:10 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:10 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:10 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:52:10 volumio5bern volumio[10197]: info: CoreStateMachine::getQueue Jun 16 18:52:10 volumio5bern volumio[10197]: info: CorePlayQueue::getQueue Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::servicePause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePause Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::pause Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: Jun 16 18:52:11 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:11 volumio5bern volumio[10197]: info: Jun 16 18:52:11 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:11 volumio5bern volumio[10197]: info: Jun 16 18:52:11 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6514,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6514,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:11 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6514,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:11 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:11 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:11 volumio5bern volumio[10197]: info: ------------------------------ 15ms Jun 16 18:52:11 volumio5bern volumio[10197]: info: ------------------------------ 15ms Jun 16 18:52:11 volumio5bern volumio[10197]: info: ------------------------------ 15ms Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:11 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPlay Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::play index undefined Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::resume Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand play took 7 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: info: Jun 16 18:52:13 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:13 volumio5bern volumio[10197]: info: Jun 16 18:52:13 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:13 volumio5bern volumio[10197]: info: Jun 16 18:52:13 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand status took 4 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand status took 4 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand status took 4 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7417,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: ------------------------------ 12ms Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7428,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:13 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7440,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:13 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:13 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:13 volumio5bern volumio[10197]: info: ------------------------------ 27ms Jun 16 18:52:13 volumio5bern volumio[10197]: info: ------------------------------ 28ms Jun 16 18:52:13 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:52:13 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: ON Jun 16 18:52:13 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:13 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:13 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:13 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:52:13 volumio5bern volumio[10197]: info: CoreStateMachine::getQueue Jun 16 18:52:13 volumio5bern volumio[10197]: info: CorePlayQueue::getQueue Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::servicePause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePause Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::pause Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: info: Jun 16 18:52:19 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:19 volumio5bern volumio[10197]: info: Jun 16 18:52:19 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:19 volumio5bern volumio[10197]: info: Jun 16 18:52:19 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand status took 3 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13279,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13279,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:19 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13279,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:19 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:19 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:19 volumio5bern volumio[10197]: info: ------------------------------ 17ms Jun 16 18:52:19 volumio5bern volumio[10197]: info: ------------------------------ 17ms Jun 16 18:52:19 volumio5bern volumio[10197]: info: ------------------------------ 15ms Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:19 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 00 KEY_ENTER RC-1204 Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 01 KEY_ENTER RC-1204 Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 00 KEY_ENTER RC-1204 Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:28 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 01 KEY_ENTER RC-1204 Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 02 KEY_ENTER RC-1204 Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 00 KEY_ENTER RC-1204 Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 01 KEY_ENTER RC-1204 Jun 16 18:52:29 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 02 KEY_ENTER RC-1204 Jun 16 18:52:30 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 03 KEY_ENTER RC-1204 Jun 16 18:52:30 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8842c0 00 KEY_DOWN RC-1204 Jun 16 18:52:30 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:30 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:30 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8842c0 01 KEY_DOWN RC-1204 Jun 16 18:52:30 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8842c0 02 KEY_DOWN RC-1204 Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8842c0 03 KEY_DOWN RC-1204 Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8842c0 04 KEY_DOWN RC-1204 Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 00 KEY_ENTER RC-1204 Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:31 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:32 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8242ca 01 KEY_ENTER RC-1204 Jun 16 18:52:32 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 00 KEY_UP RC-1204 Jun 16 18:52:32 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:32 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 01 KEY_UP RC-1204 Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 02 KEY_UP RC-1204 Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 03 KEY_UP RC-1204 Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 04 KEY_UP RC-1204 Jun 16 18:52:33 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 05 KEY_UP RC-1204 Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 00 KEY_UP RC-1204 Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8042c8 01 KEY_UP RC-1204 Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 00 KEY_RIGHT RC-1204 Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:34 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 01 KEY_RIGHT RC-1204 Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 02 KEY_RIGHT RC-1204 Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 03 KEY_RIGHT RC-1204 Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 04 KEY_RIGHT RC-1204 Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:35 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 05 KEY_RIGHT RC-1204 Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 00 KEY_RIGHT RC-1204 Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 01 KEY_RIGHT RC-1204 Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 02 KEY_RIGHT RC-1204 Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 03 KEY_RIGHT RC-1204 Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 04 KEY_RIGHT RC-1204 Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:36 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 00 KEY_RIGHT RC-1204 Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 01 KEY_RIGHT RC-1204 Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 02 KEY_RIGHT RC-1204 Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 03 KEY_RIGHT RC-1204 Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 04 KEY_RIGHT RC-1204 Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:37 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 00 KEY_RIGHT RC-1204 Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 01 KEY_RIGHT RC-1204 Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 02 KEY_RIGHT RC-1204 Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 03 KEY_RIGHT RC-1204 Jun 16 18:52:38 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a8c42c4 04 KEY_RIGHT RC-1204 Jun 16 18:52:41 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a85028d 00 KEY_PLAYPAUSE RC-1204 Jun 16 18:52:41 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:41 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:41 volumio5bern irexec[10877]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 16 18:52:41 volumio5bern irexec[10877]: Dload Upload Total Spent Left Speed Jun 16 18:52:41 volumio5bern volumio[10197]: info: Jun 16 18:52:41 volumio5bern volumio[10197]: ---------------------------- Client requests Volumio toggle Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioToggle Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::play index undefined Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::resume Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:52:41 volumio5bern irexec[10877]: [158B blob data] Jun 16 18:52:41 volumio5bern volumio[10197]: info: Jun 16 18:52:41 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:41 volumio5bern volumio[10197]: info: sendMpdCommand play took 4 milliseconds Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:41 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:41 volumio5bern volumio[10197]: info: Jun 16 18:52:41 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:41 volumio5bern volumio[10197]: info: Jun 16 18:52:41 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:41 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:41 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13462,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: ------------------------------ 12ms Jun 16 18:52:41 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 16 18:52:41 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13634,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:41 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13668,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:41 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:41 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:41 volumio5bern volumio[10197]: info: ------------------------------ 24ms Jun 16 18:52:41 volumio5bern volumio[10197]: info: ------------------------------ 24ms Jun 16 18:52:41 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:52:41 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: ON Jun 16 18:52:41 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:41 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:41 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:41 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:52:41 volumio5bern volumio[10197]: info: CoreStateMachine::getQueue Jun 16 18:52:41 volumio5bern volumio[10197]: info: CorePlayQueue::getQueue Jun 16 18:52:41 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a85028d 01 KEY_PLAYPAUSE RC-1204 Jun 16 18:52:43 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a85028d 00 KEY_PLAYPAUSE RC-1204 Jun 16 18:52:43 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:43 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:43 volumio5bern irexec[10877]: {"time":1750092761557,"response":"toggle Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 16 18:52:43 volumio5bern irexec[10877]: Dload Upload Total Spent Left Speed Jun 16 18:52:43 volumio5bern volumio[10197]: info: Jun 16 18:52:43 volumio5bern volumio[10197]: ---------------------------- Client requests Volumio toggle Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioToggle Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::servicePause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePause Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::pause Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: Jun 16 18:52:43 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:43 volumio5bern irexec[10877]: [158B blob data] Jun 16 18:52:43 volumio5bern volumio[10197]: info: Jun 16 18:52:43 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:43 volumio5bern volumio[10197]: info: Jun 16 18:52:43 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16045,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16045,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:43 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16045,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:43 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:43 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:52:43 volumio5bern volumio[10197]: info: ------------------------------ 16ms Jun 16 18:52:43 volumio5bern volumio[10197]: info: ------------------------------ 16ms Jun 16 18:52:43 volumio5bern volumio[10197]: info: ------------------------------ 15ms Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:52:43 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:52:43 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a85028d 01 KEY_PLAYPAUSE RC-1204 Jun 16 18:52:44 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a85028d 00 KEY_PLAYPAUSE RC-1204 Jun 16 18:52:44 volumio5bern volumio[10197]: info: [IrActivityLed] Starting to blink LED Jun 16 18:52:44 volumio5bern volumio[10197]: info: [IrActivityLed] Error: ENOENT: no such file or directory, open '/sys/class/leds/led1/trigger' Jun 16 18:52:44 volumio5bern irexec[10877]: {"time":1750092763408,"response":"toggle Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 16 18:52:44 volumio5bern irexec[10877]: Dload Upload Total Spent Left Speed Jun 16 18:52:44 volumio5bern volumio[10197]: info: Jun 16 18:52:44 volumio5bern volumio[10197]: ---------------------------- Client requests Volumio toggle Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioToggle Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::play index undefined Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::resume Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:52:44 volumio5bern irexec[10877]: [158B blob data] Jun 16 18:52:44 volumio5bern volumio[10197]: info: Jun 16 18:52:44 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand play took 3 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:44 volumio5bern volumio[10197]: info: Jun 16 18:52:44 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:44 volumio5bern volumio[10197]: info: Jun 16 18:52:44 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16274,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: ------------------------------ 11ms Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16468,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:44 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16468,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:52:44 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService play Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:52:44 volumio5bern volumio[10197]: info: Received an update from plugin. extracting info from payload Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:52:44 volumio5bern volumio[10197]: info: ------------------------------ 24ms Jun 16 18:52:44 volumio5bern volumio[10197]: info: ------------------------------ 24ms Jun 16 18:52:44 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:52:44 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: ON Jun 16 18:52:44 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:44 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:44 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:44 volumio5bern volumio[10197]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:52:44 volumio5bern volumio[10197]: info: CoreStateMachine::getQueue Jun 16 18:52:44 volumio5bern volumio[10197]: info: CorePlayQueue::getQueue Jun 16 18:52:44 volumio5bern volumio[10197]: info: [IrActivityLed] ir command received: 00002a4c0a85028d 01 KEY_PLAYPAUSE RC-1204 Jun 16 18:53:23 volumio5bern volumio[10197]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:53:23 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:53:23 volumio5bern volumio[10197]: info: [ASDebug] Saving Settings: Port: 17 Jun 16 18:53:23 volumio5bern volumio[10197]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:53:23 volumio5bern volumio[10197]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:53:23 volumio5bern volumio[10197]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:53:23 volumio5bern volumio[10197]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:53:23 volumio5bern volumio[10197]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::servicePause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePause Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::pause Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: Jun 16 18:53:36 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:53:36 volumio5bern volumio[10197]: info: Jun 16 18:53:36 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:53:36 volumio5bern volumio[10197]: info: Jun 16 18:53:36 volumio5bern volumio[10197]: ---------------------------- MPD announces state update: player Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::getState Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::parseState Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68811,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68811,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:53:36 volumio5bern volumio[10197]: info: ControllerMpd::pushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::servicePushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68811,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:53:36 volumio5bern volumio[10197]: verbose: CURRENT POSITION 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::syncState stateService pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::pushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CorePlayQueue::getTrack 0 Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreCommandRouter::volumioPushState Jun 16 18:53:36 volumio5bern volumio[10197]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:53:36 volumio5bern volumio[10197]: info: ------------------------------ 17ms Jun 16 18:53:36 volumio5bern volumio[10197]: info: ------------------------------ 16ms Jun 16 18:53:36 volumio5bern volumio[10197]: info: ------------------------------ 17ms Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:53:36 volumio5bern volumio[10197]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:53:36 volumio5bern volumio[10197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 18:53:36 volumio5bern volumio[10197]: Error: EBADF: bad file descriptor, write Jun 16 18:53:36 volumio5bern volumio[10197]: at Object.writeSync (fs.js:711:3) Jun 16 18:53:36 volumio5bern volumio[10197]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 16 18:53:36 volumio5bern volumio[10197]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:223:23) Jun 16 18:53:36 volumio5bern volumio[10197]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jun 16 18:53:36 volumio5bern volumio[10197]: at listOnTimeout (internal/timers.js:554:17) Jun 16 18:53:36 volumio5bern volumio[10197]: at processTimers (internal/timers.js:497:7) { Jun 16 18:53:36 volumio5bern volumio[10197]: errno: -9, Jun 16 18:53:36 volumio5bern volumio[10197]: syscall: 'write', Jun 16 18:53:36 volumio5bern volumio[10197]: code: 'EBADF' Jun 16 18:53:36 volumio5bern volumio[10197]: } Jun 16 18:53:36 volumio5bern volumio[10197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 18:53:36 volumio5bern sudo[11317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-16 18:52 Jun 16 18:53:36 volumio5bern sudo[11317]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"