-- Logs begin at Mon 2025-06-16 18:36:18 CEST, end at Mon 2025-06-16 18:57:51 CEST. -- Jun 16 18:56:06 volumio5bern volumio[11331]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:56:06 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:56:06 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Port: 10 Jun 16 18:56:06 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:56:06 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:56:06 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:56:06 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:56:06 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:56:30 volumio5bern python3[1361]: System status: Temp 58°c, Disk space remaining 97%, IP address uid Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::servicePause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePause Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::pause Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: Jun 16 18:56:39 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand pause took 3 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:39 volumio5bern volumio[11331]: info: Jun 16 18:56:39 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:39 volumio5bern volumio[11331]: info: Jun 16 18:56:39 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150605,"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:56:39 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:39 volumio5bern volumio[11331]: info: ------------------------------ 9ms Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150605,"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:56:39 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:39 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":150605,"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:56:39 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:39 volumio5bern volumio[11331]: info: ------------------------------ 15ms Jun 16 18:56:39 volumio5bern volumio[11331]: info: ------------------------------ 15ms Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:56:39 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:56:42 volumio5bern volumio[11331]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:56:42 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:56:42 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Port: 10 Jun 16 18:56:42 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:56:42 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:56:42 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:56:42 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:56:42 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:56:48 volumio5bern volumio[11331]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:56:48 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:56:48 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Port: 10 Jun 16 18:56:48 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:56:48 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:56:48 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:56:48 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:56:48 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPlay Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::play index undefined Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::resume Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:56:51 volumio5bern volumio[11331]: info: Jun 16 18:56:51 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand play took 13 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:51 volumio5bern volumio[11331]: info: Jun 16 18:56:51 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:51 volumio5bern volumio[11331]: info: Jun 16 18:56:51 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151622,"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:56:51 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151622,"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:56:51 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:56:51 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: ------------------------------ 13ms Jun 16 18:56:51 volumio5bern volumio[11331]: info: ------------------------------ 14ms Jun 16 18:56:51 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:51 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151622,"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:56:51 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:56:51 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:51 volumio5bern volumio[11331]: info: ------------------------------ 22ms Jun 16 18:56:51 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:56:51 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: ON Jun 16 18:56:51 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:56:51 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:56:51 volumio5bern volumio[11331]: info: CoreStateMachine::getQueue Jun 16 18:56:51 volumio5bern volumio[11331]: info: CorePlayQueue::getQueue Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::servicePause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePause Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::pause Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: Jun 16 18:56:55 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:55 volumio5bern volumio[11331]: info: Jun 16 18:56:55 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:55 volumio5bern volumio[11331]: info: Jun 16 18:56:55 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand status took 3 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":156239,"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:56:55 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":156239,"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:56:55 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:55 volumio5bern volumio[11331]: info: ------------------------------ 13ms Jun 16 18:56:55 volumio5bern volumio[11331]: info: ------------------------------ 14ms Jun 16 18:56:55 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:55 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":156239,"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:56:55 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:55 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:56:55 volumio5bern volumio[11331]: info: ------------------------------ 19ms Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:56:55 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPlay Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::play index undefined Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::resume Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:56:57 volumio5bern volumio[11331]: info: Jun 16 18:56:57 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand play took 5 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:57 volumio5bern volumio[11331]: info: Jun 16 18:56:57 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:57 volumio5bern volumio[11331]: info: Jun 16 18:56:57 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156559,"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:56:57 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156605,"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:56:57 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:56:57 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: ------------------------------ 15ms Jun 16 18:56:57 volumio5bern volumio[11331]: info: ------------------------------ 15ms Jun 16 18:56:57 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:56:57 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156651,"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:56:57 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:56:57 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:56:57 volumio5bern volumio[11331]: info: ------------------------------ 23ms Jun 16 18:56:57 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:56:57 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: ON Jun 16 18:56:57 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:56:57 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:56:57 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:56:57 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:56:57 volumio5bern volumio[11331]: info: CoreStateMachine::getQueue Jun 16 18:56:57 volumio5bern volumio[11331]: info: CorePlayQueue::getQueue Jun 16 18:57:22 volumio5bern volumio[11331]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:57:22 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:57:22 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Port: 9 Jun 16 18:57:22 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:57:22 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:57:22 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:57:22 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:57:22 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:57:30 volumio5bern volumio[11331]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:57:30 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:57:30 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Port: 9 Jun 16 18:57:30 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:57:30 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:57:30 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:57:30 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:57:30 volumio5bern volumio[11331]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::servicePause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePause Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::pause Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: Jun 16 18:57:32 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:32 volumio5bern volumio[11331]: info: Jun 16 18:57:32 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:32 volumio5bern volumio[11331]: info: Jun 16 18:57:32 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":191634,"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:57:32 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":191634,"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:57:32 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:32 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":191634,"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:57:32 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:32 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:32 volumio5bern volumio[11331]: info: ------------------------------ 19ms Jun 16 18:57:32 volumio5bern volumio[11331]: info: ------------------------------ 19ms Jun 16 18:57:32 volumio5bern volumio[11331]: info: ------------------------------ 18ms Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:32 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:57:33 volumio5bern ntpd[962]: 82.197.188.130 local addr 172.22.16.147 -> Jun 16 18:57:33 volumio5bern ntpd[962]: 82.197.164.46 local addr 172.22.16.147 -> Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPlay Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::play index undefined Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::resume Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:57:35 volumio5bern volumio[11331]: info: Jun 16 18:57:35 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:35 volumio5bern volumio[11331]: info: sendMpdCommand play took 2 milliseconds Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:35 volumio5bern volumio[11331]: info: Jun 16 18:57:35 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:35 volumio5bern volumio[11331]: info: Jun 16 18:57:35 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:35 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:35 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":191782,"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:57:35 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":191782,"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:57:35 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:57:35 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":191782,"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:57:35 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:57:35 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:35 volumio5bern volumio[11331]: info: ------------------------------ 28ms Jun 16 18:57:35 volumio5bern volumio[11331]: info: ------------------------------ 28ms Jun 16 18:57:35 volumio5bern volumio[11331]: info: ------------------------------ 28ms Jun 16 18:57:35 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:57:35 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: ON Jun 16 18:57:35 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:35 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:35 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:35 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:57:35 volumio5bern volumio[11331]: info: CoreStateMachine::getQueue Jun 16 18:57:35 volumio5bern volumio[11331]: info: CorePlayQueue::getQueue Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::servicePause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePause Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::pause Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: Jun 16 18:57:39 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:39 volumio5bern volumio[11331]: info: Jun 16 18:57:39 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:39 volumio5bern volumio[11331]: info: Jun 16 18:57:39 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":196525,"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:57:39 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":196525,"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:57:39 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:39 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":196525,"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:57:39 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:39 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:39 volumio5bern volumio[11331]: info: ------------------------------ 16ms Jun 16 18:57:39 volumio5bern volumio[11331]: info: ------------------------------ 15ms Jun 16 18:57:39 volumio5bern volumio[11331]: info: ------------------------------ 14ms Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:39 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPlay Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::play index undefined Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::resume Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:57:40 volumio5bern volumio[11331]: info: Jun 16 18:57:40 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:40 volumio5bern volumio[11331]: info: sendMpdCommand play took 5 milliseconds Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:40 volumio5bern volumio[11331]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:40 volumio5bern volumio[11331]: info: Jun 16 18:57:40 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:40 volumio5bern volumio[11331]: info: Jun 16 18:57:40 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:40 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:57:40 volumio5bern volumio[11331]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":196880,"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:57:40 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: ------------------------------ 6ms Jun 16 18:57:40 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":196925,"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:57:40 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:57:40 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":196971,"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:57:40 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:57:40 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:40 volumio5bern volumio[11331]: info: ------------------------------ 21ms Jun 16 18:57:40 volumio5bern volumio[11331]: info: ------------------------------ 20ms Jun 16 18:57:40 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:57:40 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: ON Jun 16 18:57:40 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:40 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:40 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:40 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:57:40 volumio5bern volumio[11331]: info: CoreStateMachine::getQueue Jun 16 18:57:40 volumio5bern volumio[11331]: info: CorePlayQueue::getQueue Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::servicePause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePause Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::pause Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: Jun 16 18:57:41 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:41 volumio5bern volumio[11331]: info: Jun 16 18:57:41 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:41 volumio5bern volumio[11331]: info: Jun 16 18:57:41 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":198662,"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:57:41 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":198662,"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:57:41 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:41 volumio5bern volumio[11331]: info: ------------------------------ 12ms Jun 16 18:57:41 volumio5bern volumio[11331]: info: ------------------------------ 12ms Jun 16 18:57:41 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:41 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":198662,"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:57:41 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:41 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:41 volumio5bern volumio[11331]: info: ------------------------------ 16ms Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:41 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPlay Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::play index undefined Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::resume Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:57:42 volumio5bern volumio[11331]: info: Jun 16 18:57:42 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:42 volumio5bern volumio[11331]: info: sendMpdCommand play took 2 milliseconds Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:42 volumio5bern volumio[11331]: info: Jun 16 18:57:42 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:42 volumio5bern volumio[11331]: info: Jun 16 18:57:42 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:42 volumio5bern volumio[11331]: info: sendMpdCommand status took 4 milliseconds Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:42 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 16 18:57:42 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 16 18:57:42 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":199085,"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:57:42 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":199085,"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:57:42 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:57:42 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"play","position":0,"seek":199085,"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:57:42 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService play Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:57:42 volumio5bern volumio[11331]: info: Received an update from plugin. extracting info from payload Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:42 volumio5bern volumio[11331]: info: ------------------------------ 26ms Jun 16 18:57:42 volumio5bern volumio[11331]: info: ------------------------------ 26ms Jun 16 18:57:42 volumio5bern volumio[11331]: info: ------------------------------ 26ms Jun 16 18:57:42 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:57:42 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: ON Jun 16 18:57:42 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:42 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:42 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:42 volumio5bern volumio[11331]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:57:42 volumio5bern volumio[11331]: info: CoreStateMachine::getQueue Jun 16 18:57:42 volumio5bern volumio[11331]: info: CorePlayQueue::getQueue Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::servicePause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePause Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::pause Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: Jun 16 18:57:43 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:43 volumio5bern volumio[11331]: info: Jun 16 18:57:43 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:43 volumio5bern volumio[11331]: info: Jun 16 18:57:43 volumio5bern volumio[11331]: ---------------------------- MPD announces state update: player Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::getState Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::parseState Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":200674,"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:57:43 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":200674,"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:57:43 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:43 volumio5bern volumio[11331]: info: ControllerMpd::pushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::servicePushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":200674,"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:57:43 volumio5bern volumio[11331]: verbose: CURRENT POSITION 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::syncState stateService pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::pushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CorePlayQueue::getTrack 0 Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreCommandRouter::volumioPushState Jun 16 18:57:43 volumio5bern volumio[11331]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:57:43 volumio5bern volumio[11331]: info: ------------------------------ 16ms Jun 16 18:57:43 volumio5bern volumio[11331]: info: ------------------------------ 16ms Jun 16 18:57:43 volumio5bern volumio[11331]: info: ------------------------------ 16ms Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:57:43 volumio5bern volumio[11331]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:57:44 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 18:57:44 volumio5bern volumio[11331]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 16 18:57:47 volumio5bern volumio[11331]: info: [ASDebug] Port: 9 Jun 16 18:57:47 volumio5bern volumio[11331]: info: [ASDebug] Inverted: true Jun 16 18:57:47 volumio5bern volumio[11331]: info: [ASDebug] Delay: 20 Jun 16 18:57:50 volumio5bern volumio[11331]: info: [ASDebug] Port: 9 Jun 16 18:57:50 volumio5bern volumio[11331]: info: [ASDebug] Inverted: true Jun 16 18:57:50 volumio5bern volumio[11331]: info: [ASDebug] Delay: 20 Jun 16 18:57:50 volumio5bern volumio[11331]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 18:57:50 volumio5bern volumio[11331]: Error: EBADF: bad file descriptor, close Jun 16 18:57:50 volumio5bern volumio[11331]: at Object.closeSync (fs.js:439:3) Jun 16 18:57:50 volumio5bern volumio[11331]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Jun 16 18:57:50 volumio5bern volumio[11331]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Jun 16 18:57:50 volumio5bern volumio[11331]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Jun 16 18:57:50 volumio5bern volumio[11331]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Jun 16 18:57:50 volumio5bern volumio[11331]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Jun 16 18:57:50 volumio5bern volumio[11331]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 16 18:57:50 volumio5bern volumio[11331]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jun 16 18:57:50 volumio5bern volumio[11331]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Jun 16 18:57:50 volumio5bern volumio[11331]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Jun 16 18:57:50 volumio5bern volumio[11331]: at Socket.emit (events.js:315:20) Jun 16 18:57:50 volumio5bern volumio[11331]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 16 18:57:50 volumio5bern volumio[11331]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 16 18:57:50 volumio5bern volumio[11331]: errno: -9, Jun 16 18:57:50 volumio5bern volumio[11331]: syscall: 'close', Jun 16 18:57:50 volumio5bern volumio[11331]: code: 'EBADF' Jun 16 18:57:50 volumio5bern volumio[11331]: } Jun 16 18:57:50 volumio5bern volumio[11331]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 18:57:51 volumio5bern sudo[12616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-16 18:56 Jun 16 18:57:51 volumio5bern sudo[12616]: 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"