-- Logs begin at Mon 2025-06-16 18:36:18 CEST, end at Mon 2025-06-16 19:00:20 CEST. -- Jun 16 18:59:03 volumio5bern volumio[12633]: error: MyVolumio Plugins failed to start in a timely fashion Jun 16 18:59:03 volumio5bern volumio[12633]: [Metrics] CommandRouter: 71s 574.17ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::volumiosetStartupVolume Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::Close All Modals sent Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::Close All Modals sent Jun 16 18:59:03 volumio5bern volumio[12633]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 16 18:59:03 volumio5bern volumio[12633]: aplay: main:828: audio open error: Device or resource busy Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::servicePause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePause Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::pause Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: Jun 16 18:59:03 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand pause took 3 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:03 volumio5bern volumio[12633]: info: Jun 16 18:59:03 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:03 volumio5bern volumio[12633]: info: Jun 16 18:59:03 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":62937,"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:59:03 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":62937,"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:59:03 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:03 volumio5bern volumio[12633]: info: ------------------------------ 20ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: ------------------------------ 19ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:03 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":62937,"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:59:03 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:03 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:03 volumio5bern volumio[12633]: info: ------------------------------ 23ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:03 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:59:04 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 16 18:59:04 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 16 18:59:04 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 16 18:59:08 volumio5bern volumio[12633]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:59:08 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:59:08 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Port: 26 Jun 16 18:59:08 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Inverted: false Jun 16 18:59:08 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:59:08 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:59:08 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:59:08 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:59:10 volumio5bern volumio[12633]: info: BOOT COMPLETED Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPlay Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::play index undefined Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::resume Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand play took 2 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: info: Jun 16 18:59:11 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:11 volumio5bern volumio[12633]: info: Jun 16 18:59:11 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:11 volumio5bern volumio[12633]: info: Jun 16 18:59:11 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63040,"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:59:11 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63097,"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:59:11 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:11 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63142,"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:59:11 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:11 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:11 volumio5bern volumio[12633]: info: ------------------------------ 23ms Jun 16 18:59:11 volumio5bern volumio[12633]: info: ------------------------------ 23ms Jun 16 18:59:11 volumio5bern volumio[12633]: info: ------------------------------ 23ms Jun 16 18:59:11 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:59:11 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: ON Jun 16 18:59:11 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:59:11 volumio5bern volumio[12633]: info: CoreStateMachine::getQueue Jun 16 18:59:11 volumio5bern volumio[12633]: info: CorePlayQueue::getQueue Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::servicePause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePause Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::pause Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand pause took 1 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: info: Jun 16 18:59:14 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:14 volumio5bern volumio[12633]: info: Jun 16 18:59:14 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:14 volumio5bern volumio[12633]: info: Jun 16 18:59:14 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66822,"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:59:14 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:14 volumio5bern volumio[12633]: info: ------------------------------ 8ms Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66822,"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:59:14 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:14 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":66822,"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:59:14 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:14 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:14 volumio5bern volumio[12633]: info: ------------------------------ 16ms Jun 16 18:59:14 volumio5bern volumio[12633]: info: ------------------------------ 16ms Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:14 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:59:17 volumio5bern ntpd[962]: 83.150.53.164 local addr 172.22.16.147 -> Jun 16 18:59:21 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPlay Jun 16 18:59:21 volumio5bern volumio[12633]: info: CoreStateMachine::play index undefined Jun 16 18:59:21 volumio5bern volumio[12633]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:59:21 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:21 volumio5bern volumio[12633]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:59:21 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:21 volumio5bern volumio[12633]: info: ControllerMpd::resume Jun 16 18:59:21 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand play took 2 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: info: Jun 16 18:59:22 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:22 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:22 volumio5bern volumio[12633]: info: Jun 16 18:59:22 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:22 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:22 volumio5bern volumio[12633]: info: Jun 16 18:59:22 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:22 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:22 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":66925,"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:59:22 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":66982,"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:59:22 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:22 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67028,"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:59:22 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:22 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:22 volumio5bern volumio[12633]: info: ------------------------------ 21ms Jun 16 18:59:22 volumio5bern volumio[12633]: info: ------------------------------ 22ms Jun 16 18:59:22 volumio5bern volumio[12633]: info: ------------------------------ 21ms Jun 16 18:59:22 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:59:22 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: ON Jun 16 18:59:22 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:22 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:22 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:22 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:59:22 volumio5bern volumio[12633]: info: CoreStateMachine::getQueue Jun 16 18:59:22 volumio5bern volumio[12633]: info: CorePlayQueue::getQueue Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::servicePause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePause Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::pause Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand pause took 2 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: info: Jun 16 18:59:25 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:25 volumio5bern volumio[12633]: info: Jun 16 18:59:25 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:25 volumio5bern volumio[12633]: info: Jun 16 18:59:25 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70834,"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:59:25 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70834,"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:59:25 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:25 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":70834,"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:59:25 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:25 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:25 volumio5bern volumio[12633]: info: ------------------------------ 15ms Jun 16 18:59:25 volumio5bern volumio[12633]: info: ------------------------------ 15ms Jun 16 18:59:25 volumio5bern volumio[12633]: info: ------------------------------ 14ms Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:25 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPlay Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::play index undefined Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::resume Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand play took 8 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: info: Jun 16 18:59:32 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:32 volumio5bern volumio[12633]: info: Jun 16 18:59:32 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:32 volumio5bern volumio[12633]: info: Jun 16 18:59:32 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand status took 5 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand status took 0 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71851,"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:59:32 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71851,"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:59:32 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:32 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: ------------------------------ 18ms Jun 16 18:59:32 volumio5bern volumio[12633]: info: ------------------------------ 14ms Jun 16 18:59:32 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:32 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71851,"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:59:32 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:32 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:32 volumio5bern volumio[12633]: info: ------------------------------ 20ms Jun 16 18:59:32 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:59:32 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: ON Jun 16 18:59:32 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:32 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:32 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:32 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:59:32 volumio5bern volumio[12633]: info: CoreStateMachine::getQueue Jun 16 18:59:32 volumio5bern volumio[12633]: info: CorePlayQueue::getQueue Jun 16 18:59:36 volumio5bern ntpd[962]: 212.51.144.47 local addr 172.22.16.147 -> Jun 16 18:59:38 volumio5bern volumio[12633]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:59:38 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:59:38 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Port: 26 Jun 16 18:59:38 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:59:38 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:59:38 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:59:38 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:59:38 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::servicePause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePause Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::pause Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: Jun 16 18:59:43 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand pause took 5 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:43 volumio5bern volumio[12633]: info: Jun 16 18:59:43 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:43 volumio5bern volumio[12633]: info: Jun 16 18:59:43 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82845,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82845,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:43 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82845,"duration":313,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Waterloo","artist":"Abba","album":"The Complete Singles Collection CD 2","uri":"NAS/SW/ABBA/The Complete Singles Collection CD 2/03 Waterloo.mp3","trackType":"mp3"} Jun 16 18:59:43 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:43 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 18:59:43 volumio5bern volumio[12633]: info: ------------------------------ 21ms Jun 16 18:59:43 volumio5bern volumio[12633]: info: ------------------------------ 17ms Jun 16 18:59:43 volumio5bern volumio[12633]: info: ------------------------------ 16ms Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 18:59:43 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: OFF Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPlay Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::play index undefined Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::startPlaybackTimer Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::resume Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand play Jun 16 18:59:47 volumio5bern volumio[12633]: info: Jun 16 18:59:47 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:47 volumio5bern volumio[12633]: info: sendMpdCommand play took 10 milliseconds Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:47 volumio5bern volumio[12633]: info: Jun 16 18:59:47 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:47 volumio5bern volumio[12633]: info: Jun 16 18:59:47 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 18:59:47 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 18:59:47 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 18:59:47 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 18:59:47 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 16 18:59:47 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83668,"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:59:47 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83714,"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:59:47 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:47 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83725,"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:59:47 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService play Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus play Jun 16 18:59:47 volumio5bern volumio[12633]: info: Received an update from plugin. extracting info from payload Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 18:59:47 volumio5bern volumio[12633]: info: ------------------------------ 24ms Jun 16 18:59:47 volumio5bern volumio[12633]: info: ------------------------------ 24ms Jun 16 18:59:47 volumio5bern volumio[12633]: info: ------------------------------ 23ms Jun 16 18:59:47 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: pause Jun 16 18:59:47 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: ON Jun 16 18:59:47 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:47 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:47 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:47 volumio5bern volumio[12633]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioGetQueue Jun 16 18:59:47 volumio5bern volumio[12633]: info: CoreStateMachine::getQueue Jun 16 18:59:47 volumio5bern volumio[12633]: info: CorePlayQueue::getQueue Jun 16 18:59:55 volumio5bern volumio[12633]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:59:55 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:59:55 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Port: 19 Jun 16 18:59:55 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:59:55 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:59:55 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:59:55 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:59:55 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:59:56 volumio5bern ntpd[962]: 212.51.144.46 local addr 172.22.16.147 -> Jun 16 19:00:06 volumio5bern volumio[12633]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 19:00:06 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 19:00:06 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Port: 13 Jun 16 19:00:06 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 19:00:06 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 19:00:06 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Latched: false Jun 16 19:00:06 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 19:00:06 volumio5bern volumio[12633]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 19:00:06 volumio5bern volumio[12633]: error: Failed callmethod call: Error: EBADF: bad file descriptor, close Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::servicePause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePause Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::pause Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: Jun 16 19:00:20 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand pause took 3 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 19:00:20 volumio5bern volumio[12633]: info: Jun 16 19:00:20 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 19:00:20 volumio5bern volumio[12633]: info: Jun 16 19:00:20 volumio5bern volumio[12633]: ---------------------------- MPD announces state update: player Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::getState Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand status Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand status took 2 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand status took 1 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::parseState Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: ControllerMpd::parseTrackInfo Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":116114,"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 19:00:20 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":116114,"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 19:00:20 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 19:00:20 volumio5bern volumio[12633]: info: ControllerMpd::pushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::servicePushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":116114,"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 19:00:20 volumio5bern volumio[12633]: verbose: CURRENT POSITION 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::syncState stateService pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::syncState currentStatus pause Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::pushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CorePlayQueue::getTrack 0 Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreCommandRouter::volumioPushState Jun 16 19:00:20 volumio5bern volumio[12633]: info: CoreStateMachine::stPlaybackTimer Jun 16 19:00:20 volumio5bern volumio[12633]: info: ------------------------------ 19ms Jun 16 19:00:20 volumio5bern volumio[12633]: info: ------------------------------ 19ms Jun 16 19:00:20 volumio5bern volumio[12633]: info: ------------------------------ 18ms Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] CurState: pause PrevState: play Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jun 16 19:00:20 volumio5bern volumio[12633]: info: [ASDebug] Togle GPIO: OFF Jun 16 19:00:20 volumio5bern volumio[12633]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 19:00:20 volumio5bern volumio[12633]: Error: ESPIPE: invalid seek, write Jun 16 19:00:20 volumio5bern volumio[12633]: at Object.writeSync (fs.js:711:3) Jun 16 19:00:20 volumio5bern volumio[12633]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 16 19:00:20 volumio5bern volumio[12633]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:223:23) Jun 16 19:00:20 volumio5bern volumio[12633]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jun 16 19:00:20 volumio5bern volumio[12633]: at listOnTimeout (internal/timers.js:554:17) Jun 16 19:00:20 volumio5bern volumio[12633]: at processTimers (internal/timers.js:497:7) { Jun 16 19:00:20 volumio5bern volumio[12633]: errno: -29, Jun 16 19:00:20 volumio5bern volumio[12633]: syscall: 'write', Jun 16 19:00:20 volumio5bern volumio[12633]: code: 'ESPIPE' Jun 16 19:00:20 volumio5bern volumio[12633]: } Jun 16 19:00:20 volumio5bern volumio[12633]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 19:00:20 volumio5bern sudo[13509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-16 18:59 Jun 16 19:00:20 volumio5bern sudo[13509]: 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"