-- Logs begin at Mon 2025-06-16 18:36:02 CEST, end at Mon 2025-06-16 18:48:54 CEST. -- Jun 16 18:47:12 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 18:47:12 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 16 18:47:15 volumio5bern volumio[8294]: info: [ASDebug] Port: 4 Jun 16 18:47:15 volumio5bern volumio[8294]: info: [ASDebug] Inverted: false Jun 16 18:47:15 volumio5bern volumio[8294]: info: [ASDebug] Delay: 20 Jun 16 18:47:19 volumio5bern volumio[8294]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 16 18:47:24 volumio5bern volumio[8294]: info: CALLMETHOD: system_hardware ir_controller saveIROptions [object Object] Jun 16 18:47:24 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: ir_controller , saveIROptions Jun 16 18:47:24 volumio5bern volumio[8294]: info: ir_controller: LIRC correctly updated. Jun 16 18:47:25 volumio5bern sudo[9720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jun 16 18:47:25 volumio5bern sudo[9720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 18:47:25 volumio5bern lircd[9597]: lircd-0.10.1[9597]: Notice: caught signal Jun 16 18:47:25 volumio5bern lircd-0.10.1[9597]: Notice: caught signal Jun 16 18:47:25 volumio5bern systemd[1]: Stopping Flexible IR remote input/output application support... Jun 16 18:47:25 volumio5bern volumio[8294]: info: [IrActivityLed] lirc socket closed Jun 16 18:47:25 volumio5bern systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Jun 16 18:47:25 volumio5bern volumio[8294]: info: [IrActivityLed] lirc socket connect: attempt 1 of 20 Jun 16 18:47:25 volumio5bern systemd[1]: lircd.service: Succeeded. Jun 16 18:47:25 volumio5bern systemd[1]: Stopped Flexible IR remote input/output application support. Jun 16 18:47:25 volumio5bern systemd[1]: irexec.service: Succeeded. Jun 16 18:47:25 volumio5bern systemd[1]: Starting lircd(8) initialization helper tool... Jun 16 18:47:25 volumio5bern volumio[8294]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Jun 16 18:47:25 volumio5bern lircd-setup[9722]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jun 16 18:47:25 volumio5bern lircd-setup[9722]: parser = configparser.SafeConfigParser() Jun 16 18:47:25 volumio5bern systemd[1]: lircd-setup.service: Succeeded. Jun 16 18:47:25 volumio5bern systemd[1]: Started lircd(8) initialization helper tool. Jun 16 18:47:26 volumio5bern systemd[1]: Starting Flexible IR remote input/output application support... Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: lircd: Opening log, level: Info Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Version: lircd 0.10.1 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: System info: Linux volumio5bern 6.6.62-v8+ #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 aarch64 GNU/Linux Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: Initial device: /dev/lirc0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: [lirc] protocol is enabled Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: Initial device: /dev/lirc0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: [lirc] protocol is enabled Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: driver: default Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: output: /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: nodaemon: 1 Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Info: lircd: Opening log, level: Info Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Notice: Using systemd fd Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Warning: Running as root Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Info: Using remote: /home/volumio/lircd.conf. Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: logfile: syslog Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: immediate-init: 0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: permission: 666 Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: driver-options: Jun 16 18:47:26 volumio5bern systemd[1]: Started Flexible IR remote input/output application support. Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: listen: 0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: connect: (null) Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: userelease: 0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: effective_user: (null) Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: release_suffix: _EVUP Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: allow_simulate: 0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: repeat_max: 600 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: configfile: /etc/lirc/lircd.conf Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Options: dynamic_codes: (null) Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Current driver: default Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Driver API version: 3 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Driver version: 0.10.0 Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: lircd: Opening log, level: Info Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: Using systemd fd Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Warning: Running as root Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: Using remote: /home/volumio/lircd.conf. Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern sudo[9720]: pam_unix(sudo:session): session closed for user root Jun 16 18:47:26 volumio5bern volumio[8294]: info: ir_controller: systemctl restart lircd.service succeeded. Jun 16 18:47:26 volumio5bern sudo[9730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jun 16 18:47:26 volumio5bern sudo[9730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 16 18:47:26 volumio5bern systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jun 16 18:47:26 volumio5bern sudo[9730]: pam_unix(sudo:session): session closed for user root Jun 16 18:47:26 volumio5bern volumio[8294]: info: ir_controller: systemctl restart irexec.service succeeded. Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Notice: accepted new client on /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Info: [lirc] protocol is enabled Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: accepted new client on /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Info: [lirc] protocol is enabled Jun 16 18:47:26 volumio5bern volumio[8294]: info: [IrActivityLed] lirc socket connect: attempt 2 of 20 Jun 16 18:47:26 volumio5bern volumio[8294]: info: [IrActivityLed] lircd service status: active Jun 16 18:47:26 volumio5bern lircd[9725]: lircd-0.10.1[9725]: Notice: accepted new client on /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern lircd-0.10.1[9725]: Notice: accepted new client on /var/run/lirc/lircd Jun 16 18:47:26 volumio5bern volumio[8294]: info: [IrActivityLed] Connected to lirc socket Jun 16 18:47:29 volumio5bern volumio[8294]: info: CoreCommandRouter::volumioGetState Jun 16 18:47:29 volumio5bern volumio[8294]: info: CorePlayQueue::getTrack 0 Jun 16 18:48:05 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 18:48:05 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 16 18:48:09 volumio5bern volumio[8294]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 16 18:48:09 volumio5bern volumio[8294]: info: [ASDebug] Setting UI defaults Jun 16 18:48:09 volumio5bern volumio[8294]: info: [ASDebug] Port: 4 Jun 16 18:48:09 volumio5bern volumio[8294]: info: [ASDebug] Inverted: false Jun 16 18:48:09 volumio5bern volumio[8294]: info: [ASDebug] Latched: true Jun 16 18:48:09 volumio5bern volumio[8294]: info: [ASDebug] On pulse width: 10 Jun 16 18:48:09 volumio5bern volumio[8294]: info: [ASDebug] Off pulse width: 10 Jun 16 18:48:40 volumio5bern volumio[8294]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jun 16 18:48:40 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jun 16 18:48:40 volumio5bern volumio[8294]: info: [ASDebug] Saving Settings: Port: 4 Jun 16 18:48:40 volumio5bern volumio[8294]: info: [ASDebug] Saving Settings: Inverted: true Jun 16 18:48:40 volumio5bern volumio[8294]: info: [ASDebug] Saving Settings: Delay: 20 Jun 16 18:48:40 volumio5bern volumio[8294]: info: [ASDebug] Saving Settings: Latched: false Jun 16 18:48:40 volumio5bern volumio[8294]: info: [ASDebug] Saving Settings: On Pulse width: 10 Jun 16 18:48:40 volumio5bern volumio[8294]: info: [ASDebug] Saving Settings: Off Pulse width: 10 Jun 16 18:48:40 volumio5bern volumio[8294]: error: Failed callmethod call: Error: EBADF: bad file descriptor, close Jun 16 18:48:46 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 16 18:48:46 volumio5bern volumio[8294]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 16 18:48:53 volumio5bern volumio[8294]: info: Enabling plugin ampswitch Jun 16 18:48:53 volumio5bern volumio[8294]: info: Loading plugin "ampswitch"... Jun 16 18:48:53 volumio5bern volumio[8294]: info: Applying required configuration parameters for plugin ampswitch Jun 16 18:48:53 volumio5bern volumio[8294]: info: PLUGIN START: ampswitch Jun 16 18:48:53 volumio5bern volumio[8294]: info: Done. Jun 16 18:48:53 volumio5bern volumio[8294]: info: CoreCommandRouter::volumioGetState Jun 16 18:48:53 volumio5bern volumio[8294]: info: CorePlayQueue::getTrack 0 Jun 16 18:48:53 volumio5bern volumio[8294]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:48:53 volumio5bern volumio[8294]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:48:53 volumio5bern volumio[8294]: info: [ASDebug] CurState: play PrevState: play Jun 16 18:48:54 volumio5bern volumio[8294]: info: [ASDebug] Port: 4 Jun 16 18:48:54 volumio5bern volumio[8294]: info: [ASDebug] Inverted: true Jun 16 18:48:54 volumio5bern volumio[8294]: info: [ASDebug] Delay: 20 Jun 16 18:48:54 volumio5bern volumio[8294]: info: [ASDebug] Port: 4 Jun 16 18:48:54 volumio5bern volumio[8294]: info: [ASDebug] Inverted: true Jun 16 18:48:54 volumio5bern volumio[8294]: info: [ASDebug] Delay: 20 Jun 16 18:48:54 volumio5bern volumio[8294]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 18:48:54 volumio5bern volumio[8294]: Error: EBADF: bad file descriptor, close Jun 16 18:48:54 volumio5bern volumio[8294]: at Object.closeSync (fs.js:439:3) Jun 16 18:48:54 volumio5bern volumio[8294]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Jun 16 18:48:54 volumio5bern volumio[8294]: at Object. (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Jun 16 18:48:54 volumio5bern volumio[8294]: at Object.closeSync (/data/plugins/system_hardware/gpio-buttons/node_modules/graceful-fs/graceful-fs.js:51:27) Jun 16 18:48:54 volumio5bern volumio[8294]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Jun 16 18:48:54 volumio5bern volumio[8294]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Jun 16 18:48:54 volumio5bern volumio[8294]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Jun 16 18:48:54 volumio5bern volumio[8294]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 16 18:48:54 volumio5bern volumio[8294]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jun 16 18:48:54 volumio5bern volumio[8294]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Jun 16 18:48:54 volumio5bern volumio[8294]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Jun 16 18:48:54 volumio5bern volumio[8294]: at Socket.emit (events.js:315:20) Jun 16 18:48:54 volumio5bern volumio[8294]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 16 18:48:54 volumio5bern volumio[8294]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 16 18:48:54 volumio5bern volumio[8294]: errno: -9, Jun 16 18:48:54 volumio5bern volumio[8294]: syscall: 'close', Jun 16 18:48:54 volumio5bern volumio[8294]: code: 'EBADF' Jun 16 18:48:54 volumio5bern volumio[8294]: } Jun 16 18:48:54 volumio5bern volumio[8294]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 16 18:48:54 volumio5bern sudo[9930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-16 18:47 Jun 16 18:48:54 volumio5bern sudo[9930]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"