-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-03-04 10:13:38 CET. -- Mar 04 10:12:08 volumio go-librespot[1267]: time="2025-03-04T10:12:08+01:00" level=trace msg="sent dealer ping" Mar 04 10:12:08 volumio go-librespot[1267]: time="2025-03-04T10:12:08+01:00" level=trace msg="received dealer pong" Mar 04 10:12:38 volumio go-librespot[1267]: time="2025-03-04T10:12:38+01:00" level=trace msg="sent dealer ping" Mar 04 10:12:38 volumio go-librespot[1267]: time="2025-03-04T10:12:38+01:00" level=trace msg="received dealer pong" Mar 04 10:13:08 volumio go-librespot[1267]: time="2025-03-04T10:13:08+01:00" level=trace msg="sent dealer ping" Mar 04 10:13:08 volumio go-librespot[1267]: time="2025-03-04T10:13:08+01:00" level=trace msg="received dealer pong" Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::volumioGetState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::volumioPause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::stPlaybackTimer Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::servicePause Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::servicePause Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::pause Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand pause Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand pause took 1 milliseconds Mar 04 10:13:37 volumio volumio[875]: info: Mar 04 10:13:37 volumio volumio[875]: ---------------------------- MPD announces state update: player Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::getState Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand status Mar 04 10:13:37 volumio volumio[875]: info: Mar 04 10:13:37 volumio volumio[875]: ---------------------------- MPD announces state update: player Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::getState Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand status Mar 04 10:13:37 volumio volumio[875]: info: Mar 04 10:13:37 volumio volumio[875]: ---------------------------- MPD announces state update: player Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::getState Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand status Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand status took 3 milliseconds Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand status took 2 milliseconds Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand status took 1 milliseconds Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::parseState Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::parseState Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::parseState Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 10:13:37 volumio volumio[875]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::parseTrackInfo Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::parseTrackInfo Mar 04 10:13:37 volumio volumio[875]: verbose: ControllerMpd::parseTrackInfo Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::pushState Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::servicePushState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":233211,"duration":378,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Smoke On The Water 1972","artist":"Deep Purple","album":"Machine Head","uri":"USB/ESD-ISO/Smoke On The Water.mp3","trackType":"mp3"} Mar 04 10:13:37 volumio volumio[875]: verbose: CURRENT POSITION 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::syncState stateService pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::syncState currentStatus pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::pushState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::volumioPushState Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::stPlaybackTimer Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::pushState Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::servicePushState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":233211,"duration":378,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Smoke On The Water 1972","artist":"Deep Purple","album":"Machine Head","uri":"USB/ESD-ISO/Smoke On The Water.mp3","trackType":"mp3"} Mar 04 10:13:37 volumio volumio[875]: verbose: CURRENT POSITION 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::syncState stateService pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::syncState currentStatus pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::pushState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::volumioPushState Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::stPlaybackTimer Mar 04 10:13:37 volumio volumio[875]: info: ControllerMpd::pushState Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::servicePushState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":233211,"duration":378,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Smoke On The Water 1972","artist":"Deep Purple","album":"Machine Head","uri":"USB/ESD-ISO/Smoke On The Water.mp3","trackType":"mp3"} Mar 04 10:13:37 volumio volumio[875]: verbose: CURRENT POSITION 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::syncState stateService pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::syncState currentStatus pause Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::pushState Mar 04 10:13:37 volumio volumio[875]: info: CorePlayQueue::getTrack 317 Mar 04 10:13:37 volumio volumio[875]: info: CoreCommandRouter::volumioPushState Mar 04 10:13:37 volumio volumio[875]: info: CoreStateMachine::stPlaybackTimer Mar 04 10:13:37 volumio volumio[875]: info: ------------------------------ 25ms Mar 04 10:13:37 volumio volumio[875]: info: ------------------------------ 25ms Mar 04 10:13:37 volumio volumio[875]: info: ------------------------------ 24ms Mar 04 10:13:37 volumio volumio[875]: info: [GPIO_Control] Status changed: pause Mar 04 10:13:37 volumio volumio[875]: info: [GPIO_Control] Handling event: musicPause Mar 04 10:13:37 volumio volumio[875]: info: [GPIO_Control] Status changed: pause Mar 04 10:13:37 volumio volumio[875]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 10:13:37 volumio volumio[875]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 10:13:37 volumio volumio[875]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] CurState: pause PrevState: play Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] CurState: pause PrevState: play Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] CurState: pause PrevState: play Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] CurState: pause PrevState: play Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] CurState: pause PrevState: play Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] CurState: pause PrevState: play Mar 04 10:13:37 volumio volumio[875]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 04 10:13:37 volumio volumio[875]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 04 10:13:37 volumio go-librespot[1267]: time="2025-03-04T10:13:37+01:00" level=trace msg="received accesspoint ping" Mar 04 10:13:37 volumio go-librespot[1267]: time="2025-03-04T10:13:37+01:00" level=trace msg="received accesspoint pong ack" Mar 04 10:13:38 volumio go-librespot[1267]: time="2025-03-04T10:13:38+01:00" level=trace msg="sent dealer ping" Mar 04 10:13:38 volumio go-librespot[1267]: time="2025-03-04T10:13:38+01:00" level=trace msg="received dealer pong" Mar 04 10:13:38 volumio volumio[875]: info: [ASDebug] Togle GPIO: OFF Mar 04 10:13:38 volumio volumio[875]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 10:13:38 volumio volumio[875]: Error: ENODEV: no such device, write Mar 04 10:13:38 volumio volumio[875]: at Object.writeSync (fs.js:711:3) Mar 04 10:13:38 volumio volumio[875]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Mar 04 10:13:38 volumio volumio[875]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Mar 04 10:13:38 volumio volumio[875]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Mar 04 10:13:38 volumio volumio[875]: at listOnTimeout (internal/timers.js:554:17) Mar 04 10:13:38 volumio volumio[875]: at processTimers (internal/timers.js:497:7) { Mar 04 10:13:38 volumio volumio[875]: errno: -19, Mar 04 10:13:38 volumio volumio[875]: syscall: 'write', Mar 04 10:13:38 volumio volumio[875]: code: 'ENODEV' Mar 04 10:13:38 volumio volumio[875]: } Mar 04 10:13:38 volumio volumio[875]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 10:13:38 volumio sudo[14956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-04 10:12 Mar 04 10:13:38 volumio sudo[14956]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"