-- Logs begin at Sun 2025-06-15 23:08:13 -04, end at Tue 2025-06-17 13:03:48 -04. -- Jun 17 13:02:13 volumio sshd[11739]: pam_unix(sshd:session): session closed for user volumio Jun 17 13:02:13 volumio systemd-logind[283]: Session 8 logged out. Waiting for processes to exit. Jun 17 13:02:13 volumio systemd[1]: session-8.scope: Succeeded. Jun 17 13:02:13 volumio systemd-logind[283]: Removed session 8. Jun 17 13:02:16 volumio volumio[12459]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 17 13:02:16 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 17 13:02:16 volumio volumio[12459]: info: [ASDebug] Saving Settings: Port: 2 Jun 17 13:02:16 volumio volumio[12459]: info: [ASDebug] Saving Settings: Inverted: false Jun 17 13:02:16 volumio volumio[12459]: info: [ASDebug] Saving Settings: Delay: 720 Jun 17 13:02:16 volumio volumio[12459]: info: [ASDebug] Saving Settings: Latched: false Jun 17 13:02:16 volumio volumio[12459]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jun 17 13:02:16 volumio volumio[12459]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jun 17 13:02:19 volumio volumio[12459]: info: CoreCommandRouter::volumioPause Jun 17 13:02:19 volumio volumio[12459]: info: CoreStateMachine::pause Jun 17 13:02:19 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:02:19 volumio volumio[12459]: info: CoreStateMachine::servicePause Jun 17 13:02:19 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:19 volumio volumio[12459]: info: CoreCommandRouter::servicePause Jun 17 13:02:19 volumio volumio[12459]: info: ControllerMpd::pause Jun 17 13:02:19 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand pause Jun 17 13:02:20 volumio volumio[12459]: info: Jun 17 13:02:20 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:02:20 volumio volumio[12459]: info: sendMpdCommand pause took 96 milliseconds Jun 17 13:02:20 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:02:20 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:02:20 volumio volumio[12459]: info: sendMpdCommand status took 4 milliseconds Jun 17 13:02:20 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:02:20 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:02:20 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 13:02:20 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:02:20 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:02:20 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:02:20 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:20 volumio volumio[12459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":256971,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"If Leaving Me Is Easy","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/03 - If Leaving Me Is Easy.mp3","trackType":"mp3"} Jun 17 13:02:20 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:02:20 volumio volumio[12459]: info: CoreStateMachine::syncState stateService pause Jun 17 13:02:20 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:02:20 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:02:20 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:20 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:02:20 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:02:20 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:02:20 volumio volumio[12459]: info: ------------------------------ 135ms Jun 17 13:02:20 volumio volumio[12459]: info: [ASDebug] CurState: pause PrevState: play Jun 17 13:02:20 volumio volumio[12459]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 17 13:02:20 volumio volumio[12459]: info: [ASDebug] Togle GPIO: OFF Jun 17 13:02:21 volumio volumio[12459]: info: CoreCommandRouter::volumioPlay Jun 17 13:02:21 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:02:21 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:02:21 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:21 volumio volumio[12459]: info: CoreStateMachine::startPlaybackTimer Jun 17 13:02:21 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:21 volumio volumio[12459]: info: ControllerMpd::resume Jun 17 13:02:21 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand play Jun 17 13:02:21 volumio volumio[12459]: info: sendMpdCommand play took 80 milliseconds Jun 17 13:02:21 volumio volumio[12459]: info: Jun 17 13:02:21 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:02:21 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:02:21 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:02:21 volumio volumio[12459]: info: sendMpdCommand status took 4 milliseconds Jun 17 13:02:21 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:02:21 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:02:21 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 17 13:02:21 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:02:21 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:02:21 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:02:21 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:21 volumio volumio[12459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257862,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"If Leaving Me Is Easy","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/03 - If Leaving Me Is Easy.mp3","trackType":"mp3"} Jun 17 13:02:21 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:02:21 volumio volumio[12459]: info: CoreStateMachine::syncState stateService play Jun 17 13:02:21 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:02:21 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:02:21 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:21 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:02:21 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:02:21 volumio volumio[12459]: info: ------------------------------ 145ms Jun 17 13:02:21 volumio volumio[12459]: info: [ASDebug] CurState: play PrevState: pause Jun 17 13:02:21 volumio volumio[12459]: info: [ASDebug] Togle GPIO: ON Jun 17 13:02:23 volumio volumio[12459]: info: CoreCommandRouter::volumioGetState Jun 17 13:02:23 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:26 volumio volumio[12459]: info: CoreCommandRouter::volumioPause Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::pause Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::servicePause Jun 17 13:02:26 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:26 volumio volumio[12459]: info: CoreCommandRouter::servicePause Jun 17 13:02:26 volumio volumio[12459]: info: ControllerMpd::pause Jun 17 13:02:26 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand pause Jun 17 13:02:26 volumio volumio[12459]: info: Jun 17 13:02:26 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:02:26 volumio volumio[12459]: info: sendMpdCommand pause took 12 milliseconds Jun 17 13:02:26 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:02:26 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:02:26 volumio volumio[12459]: info: sendMpdCommand status took 6 milliseconds Jun 17 13:02:26 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:02:26 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:02:26 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 13:02:26 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:02:26 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:02:26 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:02:26 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:26 volumio volumio[12459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":263268,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"If Leaving Me Is Easy","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/03 - If Leaving Me Is Easy.mp3","trackType":"mp3"} Jun 17 13:02:26 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::syncState stateService pause Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:02:26 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:26 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:02:26 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:02:26 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:02:26 volumio volumio[12459]: info: ------------------------------ 121ms Jun 17 13:02:26 volumio volumio[12459]: info: [ASDebug] CurState: pause PrevState: play Jun 17 13:02:26 volumio volumio[12459]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 17 13:02:27 volumio volumio[12459]: info: [ASDebug] Togle GPIO: OFF Jun 17 13:02:28 volumio volumio[12459]: info: CoreCommandRouter::volumioPlay Jun 17 13:02:28 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:02:28 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:02:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:28 volumio volumio[12459]: info: CoreStateMachine::startPlaybackTimer Jun 17 13:02:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:28 volumio volumio[12459]: info: ControllerMpd::resume Jun 17 13:02:28 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand play Jun 17 13:02:28 volumio volumio[12459]: info: sendMpdCommand play took 79 milliseconds Jun 17 13:02:28 volumio volumio[12459]: info: Jun 17 13:02:28 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:02:28 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:02:28 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:02:28 volumio volumio[12459]: info: sendMpdCommand status took 12 milliseconds Jun 17 13:02:28 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:02:28 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:02:28 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 17 13:02:28 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:02:28 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:02:28 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:02:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:28 volumio volumio[12459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264125,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"If Leaving Me Is Easy","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/03 - If Leaving Me Is Easy.mp3","trackType":"mp3"} Jun 17 13:02:28 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:02:28 volumio volumio[12459]: info: CoreStateMachine::syncState stateService play Jun 17 13:02:28 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:02:28 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:02:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:28 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:02:28 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:02:28 volumio volumio[12459]: info: ------------------------------ 243ms Jun 17 13:02:28 volumio volumio[12459]: info: [ASDebug] CurState: play PrevState: pause Jun 17 13:02:28 volumio volumio[12459]: info: [ASDebug] Togle GPIO: ON Jun 17 13:02:33 volumio sshd[12984]: Accepted password for volumio from 192.168.1.47 port 47016 ssh2 Jun 17 13:02:33 volumio sshd[12984]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 17 13:02:33 volumio systemd-logind[283]: New session 9 of user volumio. Jun 17 13:02:33 volumio systemd[1]: Started Session 9 of user volumio. Jun 17 13:02:34 volumio sudo[12997]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c touch /testsudo.tmp && rm -rf /testsudo.tmp Jun 17 13:02:34 volumio sudo[12997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:34 volumio sudo[12997]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:34 volumio sudo[13002]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c if [ -e /sys/class/gpio/gpio2 ] ; then cat /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/value ; else tee /sys/class/gpio/export <<< "2" > /dev/null && cat /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/value ; fi Jun 17 13:02:34 volumio sudo[13002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:34 volumio sudo[13002]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:35 volumio sudo[13008]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c if [ -e /sys/class/gpio/gpio27 ] ; then cat /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/value ; else tee /sys/class/gpio/export <<< "27" > /dev/null && cat /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/value ; fi Jun 17 13:02:35 volumio sudo[13008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:35 volumio sudo[13008]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:37 volumio sudo[13014]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo out > /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/value Jun 17 13:02:37 volumio sudo[13014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:37 volumio sudo[13014]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:39 volumio sudo[13019]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo in > /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/value Jun 17 13:02:39 volumio sudo[13019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:39 volumio sudo[13019]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:43 volumio sudo[13024]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo out > /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/value Jun 17 13:02:43 volumio sudo[13024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:43 volumio sudo[13024]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:45 volumio sudo[13029]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo in > /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/value Jun 17 13:02:45 volumio sudo[13029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:45 volumio sudo[13029]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:48 volumio volumio[12459]: info: CoreCommandRouter::volumioPause Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::pause Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::servicePause Jun 17 13:02:48 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:48 volumio volumio[12459]: info: CoreCommandRouter::servicePause Jun 17 13:02:48 volumio volumio[12459]: info: ControllerMpd::pause Jun 17 13:02:48 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand pause Jun 17 13:02:48 volumio volumio[12459]: info: Jun 17 13:02:48 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:02:48 volumio volumio[12459]: info: sendMpdCommand pause took 48 milliseconds Jun 17 13:02:48 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:02:48 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:02:48 volumio volumio[12459]: info: sendMpdCommand status took 5 milliseconds Jun 17 13:02:48 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:02:48 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:02:48 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 13:02:48 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:02:48 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:02:48 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:02:48 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:48 volumio volumio[12459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":284662,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"If Leaving Me Is Easy","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/03 - If Leaving Me Is Easy.mp3","trackType":"mp3"} Jun 17 13:02:48 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::syncState stateService pause Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:02:48 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:48 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:02:48 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:02:48 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:02:48 volumio volumio[12459]: info: ------------------------------ 105ms Jun 17 13:02:48 volumio volumio[12459]: info: [ASDebug] CurState: pause PrevState: play Jun 17 13:02:48 volumio volumio[12459]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 17 13:02:49 volumio volumio[12459]: info: [ASDebug] Togle GPIO: OFF Jun 17 13:02:50 volumio volumio[12459]: info: CoreCommandRouter::volumioPlay Jun 17 13:02:50 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:02:50 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:02:50 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:50 volumio volumio[12459]: info: CoreStateMachine::startPlaybackTimer Jun 17 13:02:50 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:50 volumio volumio[12459]: info: ControllerMpd::resume Jun 17 13:02:50 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand play Jun 17 13:02:50 volumio volumio[12459]: info: sendMpdCommand play took 74 milliseconds Jun 17 13:02:50 volumio volumio[12459]: info: Jun 17 13:02:50 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:02:50 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:02:50 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:02:50 volumio volumio[12459]: info: sendMpdCommand status took 5 milliseconds Jun 17 13:02:50 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:02:50 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:02:50 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 17 13:02:50 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:02:50 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:02:50 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:02:50 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:50 volumio volumio[12459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285554,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"If Leaving Me Is Easy","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/03 - If Leaving Me Is Easy.mp3","trackType":"mp3"} Jun 17 13:02:50 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:02:50 volumio volumio[12459]: info: CoreStateMachine::syncState stateService play Jun 17 13:02:50 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:02:50 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:02:50 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:02:50 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:02:50 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:02:50 volumio volumio[12459]: info: ------------------------------ 165ms Jun 17 13:02:50 volumio volumio[12459]: info: [ASDebug] CurState: play PrevState: pause Jun 17 13:02:50 volumio volumio[12459]: info: [ASDebug] Togle GPIO: ON Jun 17 13:02:52 volumio sudo[13035]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo out > /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/value Jun 17 13:02:52 volumio sudo[13035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:52 volumio sudo[13035]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:53 volumio sudo[13040]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo in > /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/direction && cat /sys/class/gpio/gpio27/value Jun 17 13:02:53 volumio sudo[13040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:53 volumio sudo[13040]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:55 volumio sudo[13045]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo out > /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/value Jun 17 13:02:55 volumio sudo[13045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:55 volumio sudo[13045]: pam_unix(sudo:session): session closed for user root Jun 17 13:02:56 volumio sudo[13050]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c echo in > /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/direction && cat /sys/class/gpio/gpio2/value Jun 17 13:02:56 volumio sudo[13050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 13:02:56 volumio sudo[13050]: pam_unix(sudo:session): session closed for user root Jun 17 13:03:01 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 17 13:03:01 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 17 13:03:04 volumio volumio[12459]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 17 13:03:04 volumio volumio[12459]: info: [ASDebug] Setting UI defaults Jun 17 13:03:04 volumio volumio[12459]: info: [ASDebug] Port: 2 Jun 17 13:03:04 volumio volumio[12459]: info: [ASDebug] Inverted: false Jun 17 13:03:04 volumio volumio[12459]: info: [ASDebug] Latched: false Jun 17 13:03:04 volumio volumio[12459]: info: [ASDebug] On pulse width: 500 Jun 17 13:03:04 volumio volumio[12459]: info: [ASDebug] Off pulse width: 500 Jun 17 13:03:05 volumio volumio[12459]: info: Jun 17 13:03:05 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:03:05 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:03:05 volumio volumio[12459]: info: sendMpdCommand status took 4 milliseconds Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:03:05 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:03:05 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:03:05 volumio volumio[12459]: info: CorePlayQueue::getTrack 2 Jun 17 13:03:05 volumio volumio[12459]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 17 13:03:05 volumio volumio[12459]: verbose: CURRENT POSITION 2 Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::syncState stateService stop Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus play Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:03:05 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:03:05 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::startPlaybackTimer Jun 17 13:03:05 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::clearAddPlayTracks NAS/ServiNas/Phil Collins - The Singles [1-3]/04 - Thru These Walls.mp3 Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 13:03:05 volumio volumio[12459]: info: ------------------------------ 177ms Jun 17 13:03:05 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:03:05 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:03:05 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:03:05 volumio volumio[12459]: info: sendMpdCommand stop took 68 milliseconds Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 13:03:05 volumio volumio[12459]: info: [ASDebug] CurState: play PrevState: play Jun 17 13:03:05 volumio volumio[12459]: info: [ASDebug] CurState: stop PrevState: play Jun 17 13:03:05 volumio volumio[12459]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 17 13:03:05 volumio volumio[12459]: info: [ASDebug] CurState: stop PrevState: play Jun 17 13:03:05 volumio volumio[12459]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 17 13:03:05 volumio volumio[12459]: info: Jun 17 13:03:05 volumio volumio[12459]: ---------------------------- MPD announces system playlist update Jun 17 13:03:05 volumio volumio[12459]: info: Ignoring MPD Status Update Jun 17 13:03:05 volumio volumio[12459]: info: sendMpdCommand clear took 77 milliseconds Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand add "NAS/ServiNas/Phil Collins - The Singles [1-3]/04 - Thru These Walls.mp3" Jun 17 13:03:05 volumio volumio[12459]: error: updateQueue error: null Jun 17 13:03:05 volumio volumio[12459]: info: Jun 17 13:03:05 volumio volumio[12459]: ---------------------------- MPD announces system playlist update Jun 17 13:03:05 volumio volumio[12459]: info: Ignoring MPD Status Update Jun 17 13:03:05 volumio volumio[12459]: info: ------------------------------ 40ms Jun 17 13:03:05 volumio volumio[12459]: info: sendMpdCommand add "NAS/ServiNas/Phil Collins - The Singles [1-3]/04 - Thru These Walls.mp3" took 32 milliseconds Jun 17 13:03:05 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand play Jun 17 13:03:05 volumio volumio[12459]: info: ------------------------------ 34ms Jun 17 13:03:05 volumio volumio[12459]: info: sendMpdCommand play took 28 milliseconds Jun 17 13:03:06 volumio volumio[12459]: info: Jun 17 13:03:06 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:03:06 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:03:06 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:03:06 volumio volumio[12459]: info: sendMpdCommand status took 11 milliseconds Jun 17 13:03:06 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:03:06 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:03:06 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 13:03:06 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:03:06 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:03:06 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:03:06 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:06 volumio volumio[12459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thru These Walls","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/04 - Thru These Walls.mp3","trackType":"mp3"} Jun 17 13:03:06 volumio volumio[12459]: verbose: CURRENT POSITION 3 Jun 17 13:03:06 volumio volumio[12459]: info: CoreStateMachine::syncState stateService play Jun 17 13:03:06 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus stop Jun 17 13:03:06 volumio volumio[12459]: info: ------------------------------ 81ms Jun 17 13:03:06 volumio volumio[12459]: info: [ASDebug] Togle GPIO: OFF Jun 17 13:03:15 volumio volumio[12459]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 17 13:03:15 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 17 13:03:15 volumio volumio[12459]: info: [ASDebug] Saving Settings: Port: 2 Jun 17 13:03:15 volumio volumio[12459]: info: [ASDebug] Saving Settings: Inverted: true Jun 17 13:03:15 volumio volumio[12459]: info: [ASDebug] Saving Settings: Delay: 720 Jun 17 13:03:15 volumio volumio[12459]: info: [ASDebug] Saving Settings: Latched: false Jun 17 13:03:15 volumio volumio[12459]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jun 17 13:03:15 volumio volumio[12459]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jun 17 13:03:19 volumio volumio[12459]: info: CoreCommandRouter::volumioPlay Jun 17 13:03:19 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:03:19 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:03:19 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:21 volumio volumio[12459]: info: CoreCommandRouter::volumioPlay Jun 17 13:03:21 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:03:21 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:03:21 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:24 volumio volumio[12459]: info: CoreCommandRouter::volumioGetState Jun 17 13:03:24 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:27 volumio volumio[12459]: info: CoreCommandRouter::volumioPause Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::pause Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::servicePause Jun 17 13:03:27 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:27 volumio volumio[12459]: info: CoreCommandRouter::servicePause Jun 17 13:03:27 volumio volumio[12459]: info: ControllerMpd::pause Jun 17 13:03:27 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand pause Jun 17 13:03:27 volumio volumio[12459]: info: Jun 17 13:03:27 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:03:27 volumio volumio[12459]: info: sendMpdCommand pause took 94 milliseconds Jun 17 13:03:27 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:03:27 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:03:27 volumio volumio[12459]: info: sendMpdCommand status took 4 milliseconds Jun 17 13:03:27 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:03:27 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:03:27 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 13:03:27 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:03:27 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:03:27 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:03:27 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:27 volumio volumio[12459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21782,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Thru These Walls","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/04 - Thru These Walls.mp3","trackType":"mp3"} Jun 17 13:03:27 volumio volumio[12459]: verbose: CURRENT POSITION 3 Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::syncState stateService pause Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:03:27 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:27 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:03:27 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:03:27 volumio volumio[12459]: info: CoreStateMachine::stPlaybackTimer Jun 17 13:03:27 volumio volumio[12459]: info: ------------------------------ 135ms Jun 17 13:03:27 volumio volumio[12459]: info: [ASDebug] CurState: pause PrevState: stop Jun 17 13:03:28 volumio volumio[12459]: info: CoreCommandRouter::volumioPlay Jun 17 13:03:28 volumio volumio[12459]: info: CoreStateMachine::play index undefined Jun 17 13:03:28 volumio volumio[12459]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 13:03:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:28 volumio volumio[12459]: info: CoreStateMachine::startPlaybackTimer Jun 17 13:03:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:28 volumio volumio[12459]: info: ControllerMpd::resume Jun 17 13:03:28 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand play Jun 17 13:03:28 volumio volumio[12459]: info: sendMpdCommand play took 83 milliseconds Jun 17 13:03:28 volumio volumio[12459]: info: Jun 17 13:03:28 volumio volumio[12459]: ---------------------------- MPD announces state update: player Jun 17 13:03:28 volumio volumio[12459]: info: ControllerMpd::getState Jun 17 13:03:28 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand status Jun 17 13:03:28 volumio volumio[12459]: info: sendMpdCommand status took 4 milliseconds Jun 17 13:03:28 volumio volumio[12459]: verbose: ControllerMpd::parseState Jun 17 13:03:28 volumio volumio[12459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 13:03:28 volumio volumio[12459]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 17 13:03:28 volumio volumio[12459]: verbose: ControllerMpd::parseTrackInfo Jun 17 13:03:28 volumio volumio[12459]: info: ControllerMpd::pushState Jun 17 13:03:28 volumio volumio[12459]: info: CoreCommandRouter::servicePushState Jun 17 13:03:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:28 volumio volumio[12459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22674,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Thru These Walls","artist":"Phil Collins","album":"The Singles","uri":"NAS/ServiNas/Phil Collins - The Singles [1-3]/04 - Thru These Walls.mp3","trackType":"mp3"} Jun 17 13:03:28 volumio volumio[12459]: verbose: CURRENT POSITION 3 Jun 17 13:03:28 volumio volumio[12459]: info: CoreStateMachine::syncState stateService play Jun 17 13:03:28 volumio volumio[12459]: info: CoreStateMachine::syncState currentStatus pause Jun 17 13:03:28 volumio volumio[12459]: info: CoreStateMachine::pushState Jun 17 13:03:28 volumio volumio[12459]: info: CorePlayQueue::getTrack 3 Jun 17 13:03:28 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 13:03:28 volumio volumio[12459]: info: CoreCommandRouter::volumioPushState Jun 17 13:03:28 volumio volumio[12459]: info: ------------------------------ 138ms Jun 17 13:03:28 volumio volumio[12459]: info: [ASDebug] CurState: play PrevState: stop Jun 17 13:03:28 volumio volumio[12459]: info: [ASDebug] Togle GPIO: ON Jun 17 13:03:38 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 17 13:03:38 volumio volumio[12459]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 17 13:03:43 volumio volumio[12459]: info: [ASDebug] Port: 2 Jun 17 13:03:43 volumio volumio[12459]: info: [ASDebug] Inverted: true Jun 17 13:03:43 volumio volumio[12459]: info: [ASDebug] Delay: 720 Jun 17 13:03:45 volumio volumio[12459]: info: [ASDebug] Port: 2 Jun 17 13:03:45 volumio volumio[12459]: info: [ASDebug] Inverted: true Jun 17 13:03:45 volumio volumio[12459]: info: [ASDebug] Delay: 720 Jun 17 13:03:45 volumio volumio[12459]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 13:03:45 volumio volumio[12459]: Error: EBADF: bad file descriptor, close Jun 17 13:03:45 volumio volumio[12459]: at Object.closeSync (fs.js:439:3) Jun 17 13:03:45 volumio volumio[12459]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Jun 17 13:03:45 volumio volumio[12459]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Jun 17 13:03:45 volumio volumio[12459]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Jun 17 13:03:45 volumio volumio[12459]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Jun 17 13:03:45 volumio volumio[12459]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Jun 17 13:03:45 volumio volumio[12459]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 17 13:03:45 volumio volumio[12459]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jun 17 13:03:45 volumio volumio[12459]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1530:29) Jun 17 13:03:45 volumio volumio[12459]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1356:47) Jun 17 13:03:45 volumio volumio[12459]: at Socket.emit (events.js:315:20) Jun 17 13:03:45 volumio volumio[12459]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 17 13:03:45 volumio volumio[12459]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 17 13:03:45 volumio volumio[12459]: errno: -9, Jun 17 13:03:45 volumio volumio[12459]: syscall: 'close', Jun 17 13:03:45 volumio volumio[12459]: code: 'EBADF' Jun 17 13:03:45 volumio volumio[12459]: } Jun 17 13:03:45 volumio volumio[12459]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 13:03:48 volumio sudo[13072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 13:02 Jun 17 13:03:48 volumio sudo[13072]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"