-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-04-29 23:02:04 CEST. -- Apr 29 23:01:02 beolab-8000 volumio[866]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/gpio-buttons/volumio/buster/armhf Apr 29 23:01:04 beolab-8000 volumio[866]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/gpio-buttons/volumio/buster/armhf Apr 29 23:01:04 beolab-8000 volumio[866]: info: Folder /tmp/plugins removed Apr 29 23:01:04 beolab-8000 volumio[866]: info: Check plugin dependencies Apr 29 23:01:04 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 23:01:04 beolab-8000 volumio[866]: info: Checking if plugin already exists Apr 29 23:01:04 beolab-8000 volumio[866]: info: Rename folder Apr 29 23:01:04 beolab-8000 volumio[866]: info: Folder /tmp/downloaded_plugin.zip removed Apr 29 23:01:04 beolab-8000 volumio[866]: info: Move to category Apr 29 23:01:07 beolab-8000 volumio[866]: info: Checking if install.sh is present Apr 29 23:01:07 beolab-8000 volumio[866]: info: Executing install.sh Apr 29 23:01:07 beolab-8000 sudo[6511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/gpio-buttons/install.sh Apr 29 23:01:07 beolab-8000 sudo[6511]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 23:01:07 beolab-8000 sudo[6511]: pam_unix(sudo:session): session closed for user root Apr 29 23:01:07 beolab-8000 volumio[866]: info: Initializing config Apr 29 23:01:07 beolab-8000 volumio[866]: Plugin install end detected on script Apr 29 23:01:07 beolab-8000 volumio[866]: info: Install script completed Apr 29 23:01:07 beolab-8000 volumio[866]: info: Adding reference to registry Apr 29 23:01:07 beolab-8000 volumio[866]: info: Done installing plugin. Apr 29 23:01:07 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 23:01:07 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 29 23:01:07 beolab-8000 volumio[866]: info: Folder /tmp/plugins removed Apr 29 23:01:07 beolab-8000 volumio[866]: info: Folder /tmp/downloaded_plugin.zip removed Apr 29 23:01:07 beolab-8000 volumio[866]: info: Folder /data/temp removed Apr 29 23:01:08 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 23:01:08 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 23:01:08 beolab-8000 volumio[866]: info: Discovery: Getting this device information Apr 29 23:01:08 beolab-8000 volumio[866]: info: CoreCommandRouter::volumioGetState Apr 29 23:01:08 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 23:01:09 beolab-8000 go-librespot[3147]: time="2025-04-29T23:01:09+02:00" level=trace msg="sent dealer ping" Apr 29 23:01:09 beolab-8000 go-librespot[3147]: time="2025-04-29T23:01:09+02:00" level=trace msg="received dealer pong" Apr 29 23:01:11 beolab-8000 volumio[866]: info: Enabling plugin gpio-buttons Apr 29 23:01:11 beolab-8000 volumio[866]: info: Loading plugin "gpio-buttons"... Apr 29 23:01:11 beolab-8000 volumio[866]: info: GPIO-Buttons initialized Apr 29 23:01:11 beolab-8000 volumio[866]: info: PLUGIN START: gpio-buttons Apr 29 23:01:11 beolab-8000 volumio[866]: info: GPIO-Buttons: Reading config and creating triggers... Apr 29 23:01:11 beolab-8000 volumio[866]: info: GPIO-Buttons started Apr 29 23:01:11 beolab-8000 volumio[866]: info: Done. Apr 29 23:01:11 beolab-8000 volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 29 23:01:14 beolab-8000 volumio[866]: info: Enabling plugin gpio-buttons Apr 29 23:01:14 beolab-8000 volumio[866]: info: Loading plugin "gpio-buttons"... Apr 29 23:01:14 beolab-8000 volumio[866]: info: GPIO-Buttons initialized Apr 29 23:01:14 beolab-8000 volumio[866]: info: PLUGIN START: gpio-buttons Apr 29 23:01:14 beolab-8000 volumio[866]: info: GPIO-Buttons: Reading config and creating triggers... Apr 29 23:01:14 beolab-8000 volumio[866]: info: GPIO-Buttons started Apr 29 23:01:14 beolab-8000 volumio[866]: info: Done. Apr 29 23:01:15 beolab-8000 volumio[866]: info: Enabling plugin gpio-buttons Apr 29 23:01:15 beolab-8000 volumio[866]: info: Loading plugin "gpio-buttons"... Apr 29 23:01:15 beolab-8000 volumio[866]: info: GPIO-Buttons initialized Apr 29 23:01:15 beolab-8000 volumio[866]: info: PLUGIN START: gpio-buttons Apr 29 23:01:15 beolab-8000 volumio[866]: info: GPIO-Buttons: Reading config and creating triggers... Apr 29 23:01:15 beolab-8000 volumio[866]: info: GPIO-Buttons started Apr 29 23:01:15 beolab-8000 volumio[866]: info: Done. Apr 29 23:01:19 beolab-8000 volumio[866]: info: Enabling plugin gpio-buttons Apr 29 23:01:19 beolab-8000 volumio[866]: info: Loading plugin "gpio-buttons"... Apr 29 23:01:19 beolab-8000 volumio[866]: info: GPIO-Buttons initialized Apr 29 23:01:19 beolab-8000 volumio[866]: info: PLUGIN START: gpio-buttons Apr 29 23:01:19 beolab-8000 volumio[866]: info: GPIO-Buttons: Reading config and creating triggers... Apr 29 23:01:19 beolab-8000 volumio[866]: info: GPIO-Buttons started Apr 29 23:01:19 beolab-8000 volumio[866]: info: Done. Apr 29 23:01:22 beolab-8000 volumio[866]: info: Enabling plugin ampswitch Apr 29 23:01:22 beolab-8000 volumio[866]: info: Loading plugin "ampswitch"... Apr 29 23:01:22 beolab-8000 volumio[866]: info: Applying required configuration parameters for plugin ampswitch Apr 29 23:01:22 beolab-8000 volumio[866]: info: PLUGIN START: ampswitch Apr 29 23:01:22 beolab-8000 volumio[866]: info: Done. Apr 29 23:01:22 beolab-8000 volumio[866]: info: CoreCommandRouter::volumioGetState Apr 29 23:01:22 beolab-8000 volumio[866]: info: [ASDebug] CurState: pause PrevState: pause Apr 29 23:01:22 beolab-8000 volumio[866]: info: [ASDebug] CurState: pause PrevState: pause Apr 29 23:01:22 beolab-8000 volumio[866]: info: [ASDebug] CurState: pause PrevState: pause Apr 29 23:01:24 beolab-8000 volumio[866]: info: [ASDebug] Port: 9 Apr 29 23:01:24 beolab-8000 volumio[866]: info: [ASDebug] Inverted: false Apr 29 23:01:24 beolab-8000 volumio[866]: info: [ASDebug] Delay: 720 Apr 29 23:01:26 beolab-8000 volumio[866]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 23:01:26 beolab-8000 volumio[866]: info: GPIO-Buttons: Getting UI config Apr 29 23:01:38 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 23:01:38 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 23:01:38 beolab-8000 volumio[866]: info: Discovery: Getting this device information Apr 29 23:01:38 beolab-8000 volumio[866]: info: CoreCommandRouter::volumioGetState Apr 29 23:01:38 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 23:01:39 beolab-8000 go-librespot[3147]: time="2025-04-29T23:01:39+02:00" level=trace msg="sent dealer ping" Apr 29 23:01:39 beolab-8000 go-librespot[3147]: time="2025-04-29T23:01:39+02:00" level=trace msg="received dealer pong" Apr 29 23:01:43 beolab-8000 volumio[866]: info: CALLMETHOD: system_hardware gpio-buttons saveConfig [object Object] Apr 29 23:01:43 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: gpio-buttons , saveConfig Apr 29 23:01:43 beolab-8000 volumio[866]: info: GPIO-Buttons: Reading config and creating triggers... Apr 29 23:01:43 beolab-8000 volumio[866]: info: GPIO-Buttons: playPause on pin 520 Apr 29 23:02:03 beolab-8000 volumio[866]: info: CoreCommandRouter::volumioVolatilePlay Apr 29 23:02:03 beolab-8000 volumio[866]: info: CoreStateMachine::volatilePlay Apr 29 23:02:03 beolab-8000 volumio[866]: info: Spotify Play Apr 29 23:02:03 beolab-8000 volumio[866]: info: Sending Spotify command to local API: /player/resume Apr 29 23:02:03 beolab-8000 go-librespot[3147]: time="2025-04-29T23:02:03+02:00" level=trace msg="seek to 179170ms (diff: 82ms, samples: 7901397, bytes: 6996639)" uri="spotify:track:2hIPKg0LBYhZYmwZS6eQ20" Apr 29 23:02:03 beolab-8000 go-librespot[3147]: time="2025-04-29T23:02:03+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 29 23:02:03 beolab-8000 go-librespot[3147]: time="2025-04-29T23:02:03+02:00" level=debug msg="resume track at 179088ms" Apr 29 23:02:03 beolab-8000 go-librespot[3147]: time="2025-04-29T23:02:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:02:04 beolab-8000 go-librespot[3147]: time="2025-04-29T23:02:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 29 23:02:04 beolab-8000 go-librespot[3147]: time="2025-04-29T23:02:04+02:00" level=trace msg="emitting websocket event: playing" Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2hIPKg0LBYhZYmwZS6eQ20","play_origin":"free-tier-artist"}} Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: {"status":"play","service":"spop","title":"hot slow","artist":"berlioz","album":"open this wall","albumart":"https://i.scdn.co/image/ab67616d00001e02c1c672fa0dd740c2db9e66ae","uri":"spotify:track:2hIPKg0LBYhZYmwZS6eQ20","trackType":"spotify","seek":178000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreCommandRouter::servicePushState Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreStateMachine::pushState Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreCommandRouter::volumioPushState Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2hIPKg0LBYhZYmwZS6eQ20","play_origin":"free-tier-artist"}} Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: PUSH STATE SPOTIFY Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: {"status":"play","service":"spop","title":"hot slow","artist":"berlioz","album":"open this wall","albumart":"https://i.scdn.co/image/ab67616d00001e02c1c672fa0dd740c2db9e66ae","uri":"spotify:track:2hIPKg0LBYhZYmwZS6eQ20","trackType":"spotify","seek":178000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":false,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreCommandRouter::servicePushState Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreStateMachine::pushState Apr 29 23:02:04 beolab-8000 volumio[866]: info: CoreCommandRouter::volumioPushState Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:02:04 beolab-8000 volumio[866]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 29 23:02:04 beolab-8000 volumio[866]: info: [ASDebug] CurState: play PrevState: pause Apr 29 23:02:04 beolab-8000 volumio[866]: info: [ASDebug] Togle GPIO: ON Apr 29 23:02:04 beolab-8000 volumio[866]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 23:02:04 beolab-8000 volumio[866]: Error: EBADF: bad file descriptor, write Apr 29 23:02:04 beolab-8000 volumio[866]: at Object.writeSync (fs.js:711:3) Apr 29 23:02:04 beolab-8000 volumio[866]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 29 23:02:04 beolab-8000 volumio[866]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Apr 29 23:02:04 beolab-8000 volumio[866]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Apr 29 23:02:04 beolab-8000 volumio[866]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 29 23:02:04 beolab-8000 volumio[866]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Apr 29 23:02:04 beolab-8000 volumio[866]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Apr 29 23:02:04 beolab-8000 volumio[866]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 29 23:02:04 beolab-8000 volumio[866]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 29 23:02:04 beolab-8000 volumio[866]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Apr 29 23:02:04 beolab-8000 volumio[866]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 29 23:02:04 beolab-8000 volumio[866]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 29 23:02:04 beolab-8000 volumio[866]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Apr 29 23:02:04 beolab-8000 volumio[866]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Apr 29 23:02:04 beolab-8000 volumio[866]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 29 23:02:04 beolab-8000 volumio[866]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Apr 29 23:02:04 beolab-8000 volumio[866]: errno: -9, Apr 29 23:02:04 beolab-8000 volumio[866]: syscall: 'write', Apr 29 23:02:04 beolab-8000 volumio[866]: code: 'EBADF' Apr 29 23:02:04 beolab-8000 volumio[866]: } Apr 29 23:02:04 beolab-8000 volumio[866]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 23:02:04 beolab-8000 sudo[6762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 23:01 Apr 29 23:02:04 beolab-8000 sudo[6762]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"