-- Logs begin at Wed 2025-09-10 19:11:21 CEST, end at Wed 2025-09-10 20:12:58 CEST. -- Sep 10 20:11:02 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 20:11:02 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 10 20:11:05 volumio volumio[1186]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 20:11:05 volumio volumio[1186]: info: [ASDebug] Setting UI defaults Sep 10 20:11:05 volumio volumio[1186]: info: [ASDebug] Port: 24 Sep 10 20:11:05 volumio volumio[1186]: info: [ASDebug] Inverted: false Sep 10 20:11:05 volumio volumio[1186]: info: [ASDebug] Latched: false Sep 10 20:11:05 volumio volumio[1186]: info: [ASDebug] On pulse width: 500 Sep 10 20:11:05 volumio volumio[1186]: info: [ASDebug] Off pulse width: 500 Sep 10 20:11:10 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 20:11:10 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 20:11:10 volumio volumio[1186]: info: Discovery: Getting this device information Sep 10 20:11:10 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Sep 10 20:11:10 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:11:10 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 20:11:31 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 20:11:31 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 10 20:11:36 volumio volumio[1186]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 20:11:36 volumio volumio[1186]: info: [ASDebug] Setting UI defaults Sep 10 20:11:36 volumio volumio[1186]: info: [ASDebug] Port: 24 Sep 10 20:11:36 volumio volumio[1186]: info: [ASDebug] Inverted: false Sep 10 20:11:36 volumio volumio[1186]: info: [ASDebug] Latched: false Sep 10 20:11:36 volumio volumio[1186]: info: [ASDebug] On pulse width: 500 Sep 10 20:11:36 volumio volumio[1186]: info: [ASDebug] Off pulse width: 500 Sep 10 20:11:40 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 20:11:40 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 20:11:40 volumio volumio[1186]: info: Discovery: Getting this device information Sep 10 20:11:40 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Sep 10 20:11:40 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:11:40 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 20:11:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 20:11:50 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 10 20:11:55 volumio volumio[1186]: info: [GPIO_Control] Destroying GPIO 21 Sep 10 20:11:55 volumio volumio[1186]: info: [GPIO_Control] Destroying GPIO 21 Sep 10 20:11:55 volumio volumio[1186]: info: [GPIO_Control] GPIOs destroyed Sep 10 20:11:55 volumio volumio[1186]: info: Disabling plugin gpio_control Sep 10 20:11:55 volumio volumio[1186]: info: Done. Sep 10 20:11:57 volumio volumio[1186]: info: Enabling plugin gpio_control Sep 10 20:11:57 volumio volumio[1186]: info: Loading plugin "gpio_control"... Sep 10 20:11:57 volumio volumio[1186]: info: Applying required configuration parameters for plugin gpio_control Sep 10 20:11:57 volumio volumio[1186]: info: PLUGIN START: gpio_control Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Reading config and creating GPIOs Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] On musicPlay will set GPIO 21 to On after 2 Seconds delay Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] On musicPause will set GPIO 21 to Off after 2 Seconds delay Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] On musicStop will set GPIO 21 to Off after 3 Seconds delay Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] GPIOs created Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Handling event: systemStartup Sep 10 20:11:57 volumio volumio[1186]: info: Done. Sep 10 20:11:57 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Sep 10 20:11:57 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPause Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] *** musicPause *** Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Delaying: 2000ms Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Handling event: repeatAllOff Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Handling event: repeatOff Sep 10 20:11:57 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:11:58 volumio volumio[1186]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 20:11:58 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 20:11:58 volumio volumio[1186]: info: Received Get System Info Sep 10 20:11:58 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 20:11:58 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 20:11:58 volumio volumio[1186]: info: Discovery: Getting this device information Sep 10 20:11:58 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Sep 10 20:11:58 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:11:58 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 20:11:59 volumio volumio[1186]: info: [GPIO_Control] Turning GPIO 21 Off (musicPause) Sep 10 20:12:09 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 20:12:09 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 10 20:12:10 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 20:12:10 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 20:12:10 volumio volumio[1186]: info: Discovery: Getting this device information Sep 10 20:12:10 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Sep 10 20:12:10 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:10 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 20:12:12 volumio volumio[1186]: info: [GPIO_Control] Destroying GPIO 21 Sep 10 20:12:12 volumio volumio[1186]: info: [GPIO_Control] Destroying GPIO 21 Sep 10 20:12:12 volumio volumio[1186]: info: [GPIO_Control] GPIOs destroyed Sep 10 20:12:12 volumio volumio[1186]: info: Disabling plugin gpio_control Sep 10 20:12:12 volumio volumio[1186]: info: Done. Sep 10 20:12:14 volumio volumio[1186]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 20:12:14 volumio volumio[1186]: info: [ASDebug] Setting UI defaults Sep 10 20:12:14 volumio volumio[1186]: info: [ASDebug] Port: 24 Sep 10 20:12:14 volumio volumio[1186]: info: [ASDebug] Inverted: false Sep 10 20:12:14 volumio volumio[1186]: info: [ASDebug] Latched: false Sep 10 20:12:14 volumio volumio[1186]: info: [ASDebug] On pulse width: 500 Sep 10 20:12:14 volumio volumio[1186]: info: [ASDebug] Off pulse width: 500 Sep 10 20:12:22 volumio volumio[1186]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Sep 10 20:12:22 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Sep 10 20:12:22 volumio volumio[1186]: info: [ASDebug] Saving Settings: Port: 21 Sep 10 20:12:22 volumio volumio[1186]: info: [ASDebug] Saving Settings: Inverted: false Sep 10 20:12:22 volumio volumio[1186]: info: [ASDebug] Saving Settings: Delay: 720 Sep 10 20:12:22 volumio volumio[1186]: info: [ASDebug] Saving Settings: Latched: false Sep 10 20:12:22 volumio volumio[1186]: info: [ASDebug] Saving Settings: On Pulse width: 500 Sep 10 20:12:22 volumio volumio[1186]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Sep 10 20:12:23 volumio volumio[1186]: info: CoreCommandRouter::volumioPlay Sep 10 20:12:23 volumio volumio[1186]: info: CoreStateMachine::play index undefined Sep 10 20:12:23 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 20:12:23 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:23 volumio volumio[1186]: info: CoreStateMachine::startPlaybackTimer Sep 10 20:12:23 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:23 volumio volumio[1186]: info: ControllerMpd::resume Sep 10 20:12:23 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand play Sep 10 20:12:23 volumio volumio[1186]: info: Sep 10 20:12:23 volumio volumio[1186]: ---------------------------- MPD announces state update: player Sep 10 20:12:23 volumio volumio[1186]: info: sendMpdCommand play took 7 milliseconds Sep 10 20:12:23 volumio volumio[1186]: info: ControllerMpd::getState Sep 10 20:12:23 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status Sep 10 20:12:23 volumio volumio[1186]: info: sendMpdCommand status took 1 milliseconds Sep 10 20:12:23 volumio volumio[1186]: verbose: ControllerMpd::parseState Sep 10 20:12:23 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 20:12:23 volumio volumio[1186]: error: Upnp client error: Error: This socket has been ended by the other party Sep 10 20:12:23 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 10 20:12:23 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo Sep 10 20:12:23 volumio volumio[1186]: info: ControllerMpd::pushState Sep 10 20:12:23 volumio volumio[1186]: info: CoreCommandRouter::servicePushState Sep 10 20:12:23 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:23 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20591,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"976 Kbps","isStreaming":false,"title":"I Do Both Jay And Jane","artist":"La Rissa","album":"G.R.O.O.V.E. 2001 CD1","uri":"USB/Nový_svazek/Flac HI-Res/ATB/Compilation/2000 - G.R.O.O.V.E. 2001 (62339833462)/1.14 - La Rissa - I Do Both Jay And Jane.flac","trackType":"flac"} Sep 10 20:12:23 volumio volumio[1186]: verbose: CURRENT POSITION 13 Sep 10 20:12:23 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play Sep 10 20:12:23 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause Sep 10 20:12:23 volumio volumio[1186]: info: CoreStateMachine::pushState Sep 10 20:12:23 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:23 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 20:12:23 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState Sep 10 20:12:23 volumio volumio[1186]: info: ------------------------------ 9ms Sep 10 20:12:23 volumio volumio[1186]: info: [ASDebug] CurState: play PrevState: pause Sep 10 20:12:23 volumio volumio[1186]: info: [ASDebug] Togle GPIO: ON Sep 10 20:12:23 volumio volumio[1186]: info: [GPIO_Control] Status changed: play Sep 10 20:12:23 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPlay Sep 10 20:12:23 volumio volumio[1186]: info: [GPIO_Control] Status changed: play Sep 10 20:12:23 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPlay Sep 10 20:12:23 volumio volumio[1186]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 10 20:12:37 volumio volumio[1186]: info: CoreCommandRouter::volumioPause Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::pause Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::servicePause Sep 10 20:12:37 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:37 volumio volumio[1186]: info: CoreCommandRouter::servicePause Sep 10 20:12:37 volumio volumio[1186]: info: ControllerMpd::pause Sep 10 20:12:37 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand pause Sep 10 20:12:37 volumio volumio[1186]: info: sendMpdCommand pause took 1 milliseconds Sep 10 20:12:37 volumio volumio[1186]: info: Sep 10 20:12:37 volumio volumio[1186]: ---------------------------- MPD announces state update: player Sep 10 20:12:37 volumio volumio[1186]: info: ControllerMpd::getState Sep 10 20:12:37 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status Sep 10 20:12:37 volumio volumio[1186]: info: sendMpdCommand status took 0 milliseconds Sep 10 20:12:37 volumio volumio[1186]: verbose: ControllerMpd::parseState Sep 10 20:12:37 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 20:12:37 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 10 20:12:37 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo Sep 10 20:12:37 volumio volumio[1186]: info: ControllerMpd::pushState Sep 10 20:12:37 volumio volumio[1186]: info: CoreCommandRouter::servicePushState Sep 10 20:12:37 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:37 volumio volumio[1186]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":34251,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"510 Kbps","isStreaming":false,"title":"I Do Both Jay And Jane","artist":"La Rissa","album":"G.R.O.O.V.E. 2001 CD1","uri":"USB/Nový_svazek/Flac HI-Res/ATB/Compilation/2000 - G.R.O.O.V.E. 2001 (62339833462)/1.14 - La Rissa - I Do Both Jay And Jane.flac","trackType":"flac"} Sep 10 20:12:37 volumio volumio[1186]: verbose: CURRENT POSITION 13 Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::syncState stateService pause Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::pushState Sep 10 20:12:37 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:37 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 20:12:37 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState Sep 10 20:12:37 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer Sep 10 20:12:37 volumio volumio[1186]: info: ------------------------------ 8ms Sep 10 20:12:37 volumio volumio[1186]: info: [ASDebug] CurState: pause PrevState: play Sep 10 20:12:37 volumio volumio[1186]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 10 20:12:37 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:12:37 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPause Sep 10 20:12:37 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:12:37 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPause Sep 10 20:12:37 volumio volumio[1186]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Togle GPIO: OFF Sep 10 20:12:38 volumio volumio[1186]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Sep 10 20:12:38 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Saving Settings: Port: 21 Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Saving Settings: Inverted: false Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Saving Settings: Delay: 720 Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Saving Settings: Latched: false Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Saving Settings: On Pulse width: 500 Sep 10 20:12:38 volumio volumio[1186]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Sep 10 20:12:40 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 20:12:40 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 20:12:40 volumio volumio[1186]: info: Discovery: Getting this device information Sep 10 20:12:40 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState Sep 10 20:12:40 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:40 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 20:12:41 volumio volumio[1186]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Sep 10 20:12:41 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Sep 10 20:12:41 volumio volumio[1186]: info: [ASDebug] Saving Settings: Port: 21 Sep 10 20:12:41 volumio volumio[1186]: info: [ASDebug] Saving Settings: Inverted: true Sep 10 20:12:41 volumio volumio[1186]: info: [ASDebug] Saving Settings: Delay: 720 Sep 10 20:12:41 volumio volumio[1186]: info: [ASDebug] Saving Settings: Latched: false Sep 10 20:12:41 volumio volumio[1186]: info: [ASDebug] Saving Settings: On Pulse width: 500 Sep 10 20:12:41 volumio volumio[1186]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Sep 10 20:12:44 volumio volumio[1186]: info: CoreCommandRouter::volumioPlay Sep 10 20:12:44 volumio volumio[1186]: info: CoreStateMachine::play index undefined Sep 10 20:12:44 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 20:12:44 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:44 volumio volumio[1186]: info: CoreStateMachine::startPlaybackTimer Sep 10 20:12:44 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:44 volumio volumio[1186]: info: ControllerMpd::resume Sep 10 20:12:44 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand play Sep 10 20:12:44 volumio volumio[1186]: info: Sep 10 20:12:44 volumio volumio[1186]: ---------------------------- MPD announces state update: player Sep 10 20:12:44 volumio volumio[1186]: info: sendMpdCommand play took 6 milliseconds Sep 10 20:12:44 volumio volumio[1186]: info: ControllerMpd::getState Sep 10 20:12:44 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status Sep 10 20:12:44 volumio volumio[1186]: info: sendMpdCommand status took 0 milliseconds Sep 10 20:12:44 volumio volumio[1186]: verbose: ControllerMpd::parseState Sep 10 20:12:44 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 20:12:44 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 10 20:12:44 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo Sep 10 20:12:44 volumio volumio[1186]: info: ControllerMpd::pushState Sep 10 20:12:44 volumio volumio[1186]: info: CoreCommandRouter::servicePushState Sep 10 20:12:44 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:44 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34983,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"580 Kbps","isStreaming":false,"title":"I Do Both Jay And Jane","artist":"La Rissa","album":"G.R.O.O.V.E. 2001 CD1","uri":"USB/Nový_svazek/Flac HI-Res/ATB/Compilation/2000 - G.R.O.O.V.E. 2001 (62339833462)/1.14 - La Rissa - I Do Both Jay And Jane.flac","trackType":"flac"} Sep 10 20:12:44 volumio volumio[1186]: verbose: CURRENT POSITION 13 Sep 10 20:12:44 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play Sep 10 20:12:44 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause Sep 10 20:12:44 volumio volumio[1186]: info: CoreStateMachine::pushState Sep 10 20:12:44 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:44 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 20:12:44 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState Sep 10 20:12:44 volumio volumio[1186]: info: ------------------------------ 8ms Sep 10 20:12:44 volumio volumio[1186]: info: [ASDebug] CurState: play PrevState: pause Sep 10 20:12:44 volumio volumio[1186]: info: [ASDebug] Togle GPIO: ON Sep 10 20:12:44 volumio volumio[1186]: info: [GPIO_Control] Status changed: play Sep 10 20:12:44 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPlay Sep 10 20:12:44 volumio volumio[1186]: info: [GPIO_Control] Status changed: play Sep 10 20:12:44 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPlay Sep 10 20:12:44 volumio volumio[1186]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 10 20:12:47 volumio volumio[1186]: info: CoreCommandRouter::volumioPause Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::pause Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::servicePause Sep 10 20:12:47 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:47 volumio volumio[1186]: info: CoreCommandRouter::servicePause Sep 10 20:12:47 volumio volumio[1186]: info: ControllerMpd::pause Sep 10 20:12:47 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand pause Sep 10 20:12:47 volumio volumio[1186]: info: Sep 10 20:12:47 volumio volumio[1186]: ---------------------------- MPD announces state update: player Sep 10 20:12:47 volumio volumio[1186]: info: sendMpdCommand pause took 1 milliseconds Sep 10 20:12:47 volumio volumio[1186]: info: ControllerMpd::getState Sep 10 20:12:47 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status Sep 10 20:12:47 volumio volumio[1186]: info: sendMpdCommand status took 0 milliseconds Sep 10 20:12:47 volumio volumio[1186]: verbose: ControllerMpd::parseState Sep 10 20:12:47 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 20:12:47 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 10 20:12:47 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo Sep 10 20:12:47 volumio volumio[1186]: info: ControllerMpd::pushState Sep 10 20:12:47 volumio volumio[1186]: info: CoreCommandRouter::servicePushState Sep 10 20:12:47 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:47 volumio volumio[1186]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":38415,"duration":453,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"592 Kbps","isStreaming":false,"title":"I Do Both Jay And Jane","artist":"La Rissa","album":"G.R.O.O.V.E. 2001 CD1","uri":"USB/Nový_svazek/Flac HI-Res/ATB/Compilation/2000 - G.R.O.O.V.E. 2001 (62339833462)/1.14 - La Rissa - I Do Both Jay And Jane.flac","trackType":"flac"} Sep 10 20:12:47 volumio volumio[1186]: verbose: CURRENT POSITION 13 Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::syncState stateService pause Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::pushState Sep 10 20:12:47 volumio volumio[1186]: info: CorePlayQueue::getTrack 13 Sep 10 20:12:47 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 20:12:47 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState Sep 10 20:12:47 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer Sep 10 20:12:47 volumio volumio[1186]: info: ------------------------------ 12ms Sep 10 20:12:47 volumio volumio[1186]: info: [ASDebug] CurState: pause PrevState: play Sep 10 20:12:47 volumio volumio[1186]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 10 20:12:47 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:12:47 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPause Sep 10 20:12:47 volumio volumio[1186]: info: [GPIO_Control] Status changed: pause Sep 10 20:12:47 volumio volumio[1186]: info: [GPIO_Control] Handling event: musicPause Sep 10 20:12:47 volumio volumio[1186]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 10 20:12:48 volumio volumio[1186]: info: [ASDebug] Togle GPIO: OFF Sep 10 20:12:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 20:12:49 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 10 20:12:50 volumio volumio[1186]: info: [ASDebug] Port: 21 Sep 10 20:12:50 volumio volumio[1186]: info: [ASDebug] Inverted: true Sep 10 20:12:50 volumio volumio[1186]: info: [ASDebug] Delay: 720 Sep 10 20:12:58 volumio volumio[1186]: info: Starting Uninstall of plugin system_controller - ampswitch Sep 10 20:12:58 volumio volumio[1186]: info: Uninstalling plugin ampswitch Sep 10 20:12:58 volumio volumio[1186]: info: [ASDebug] Port: 21 Sep 10 20:12:58 volumio volumio[1186]: info: [ASDebug] Inverted: true Sep 10 20:12:58 volumio volumio[1186]: info: [ASDebug] Delay: 720 Sep 10 20:12:58 volumio volumio[1186]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 20:12:58 volumio volumio[1186]: Error: EBADF: bad file descriptor, close Sep 10 20:12:58 volumio volumio[1186]: at Object.closeSync (fs.js:439:3) Sep 10 20:12:58 volumio volumio[1186]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Sep 10 20:12:58 volumio volumio[1186]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Sep 10 20:12:58 volumio volumio[1186]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Sep 10 20:12:58 volumio volumio[1186]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Sep 10 20:12:58 volumio volumio[1186]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Sep 10 20:12:58 volumio volumio[1186]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 10 20:12:58 volumio volumio[1186]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Sep 10 20:12:58 volumio volumio[1186]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Sep 10 20:12:58 volumio volumio[1186]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Sep 10 20:12:58 volumio volumio[1186]: at Socket.emit (events.js:315:20) Sep 10 20:12:58 volumio volumio[1186]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 10 20:12:58 volumio volumio[1186]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 10 20:12:58 volumio volumio[1186]: errno: -9, Sep 10 20:12:58 volumio volumio[1186]: syscall: 'close', Sep 10 20:12:58 volumio volumio[1186]: code: 'EBADF' Sep 10 20:12:58 volumio volumio[1186]: } Sep 10 20:12:58 volumio volumio[1186]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 20:12:58 volumio sudo[18073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 20:11 Sep 10 20:12:58 volumio sudo[18073]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"