-- Logs begin at Sat 2025-07-05 15:18:29 EEST, end at Sat 2025-07-05 15:25:20 EEST. -- Jul 05 15:24:13 volumio go-librespot[1436]: time="2025-07-05T15:24:13+03:00" level=trace msg="sent dealer ping" Jul 05 15:24:13 volumio go-librespot[1436]: time="2025-07-05T15:24:13+03:00" level=trace msg="received dealer pong" Jul 05 15:24:42 volumio go-librespot[1436]: time="2025-07-05T15:24:42+03:00" level=trace msg="received accesspoint ping" Jul 05 15:24:42 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 05 15:24:42 volumio go-librespot[1436]: time="2025-07-05T15:24:42+03:00" level=trace msg="received accesspoint pong ack" Jul 05 15:24:43 volumio go-librespot[1436]: time="2025-07-05T15:24:43+03:00" level=trace msg="sent dealer ping" Jul 05 15:24:43 volumio go-librespot[1436]: time="2025-07-05T15:24:43+03:00" level=trace msg="received dealer pong" Jul 05 15:24:55 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 15:25:13 volumio go-librespot[1436]: time="2025-07-05T15:25:13+03:00" level=trace msg="sent dealer ping" Jul 05 15:25:13 volumio go-librespot[1436]: time="2025-07-05T15:25:13+03:00" level=trace msg="received dealer pong" Jul 05 15:25:18 volumio volumio[1058]: info: CALLMETHOD: system_hardware ir_controller saveIROptions [object Object] Jul 05 15:25:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: ir_controller , saveIROptions Jul 05 15:25:19 volumio volumio[1058]: info: ir_controller: LIRC correctly updated. Jul 05 15:25:20 volumio sudo[6236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 05 15:25:20 volumio sudo[6236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 15:25:20 volumio lircd[1363]: lircd-0.10.1[1363]: Notice: caught signal Jul 05 15:25:20 volumio lircd-0.10.1[1363]: Notice: caught signal Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket closed Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 1 of 20 Jul 05 15:25:20 volumio systemd[1]: Stopping Flexible IR remote input/output application support... Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket closed Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 1 of 20 Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket closed Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 1 of 20 Jul 05 15:25:20 volumio systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Jul 05 15:25:20 volumio systemd[1]: lircd.service: Succeeded. Jul 05 15:25:20 volumio systemd[1]: Stopped Flexible IR remote input/output application support. Jul 05 15:25:20 volumio systemd[1]: irexec.service: Succeeded. Jul 05 15:25:20 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lircd service is inactive, skipping connection attempt. Error: Command failed: /bin/systemctl is-active lircd Jul 05 15:25:20 volumio lircd-setup[6239]: /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. Jul 05 15:25:20 volumio lircd-setup[6239]: parser = configparser.SafeConfigParser() Jul 05 15:25:20 volumio systemd[1]: lircd-setup.service: Succeeded. Jul 05 15:25:20 volumio systemd[1]: Started lircd(8) initialization helper tool. Jul 05 15:25:20 volumio systemd[1]: Starting Flexible IR remote input/output application support... Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: lircd: Opening log, level: Info Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Version: lircd 0.10.1 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: System info: Linux volumio 6.6.62-v8+ #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 aarch64 GNU/Linux Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: Initial device: /dev/lirc0 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: [lirc] protocol is enabled Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: Initial device: /dev/lirc0 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: [lirc] protocol is enabled Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: driver: default Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Info: lircd: Opening log, level: Info Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: output: /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Notice: Using systemd fd Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Warning: Running as root Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Info: Using remote: arcam-irdac2. Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: nodaemon: 1 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: logfile: syslog Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: immediate-init: 0 Jul 05 15:25:20 volumio systemd[1]: Started Flexible IR remote input/output application support. Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: permission: 666 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: driver-options: Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: listen: 0 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: connect: (null) Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: userelease: 0 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: effective_user: (null) Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: release_suffix: _EVUP Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: allow_simulate: 0 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: repeat_max: 600 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: configfile: /etc/lirc/lircd.conf Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Options: dynamic_codes: (null) Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Current driver: default Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Driver API version: 3 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Driver version: 0.10.0 Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: lircd: Opening log, level: Info Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: Using systemd fd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Warning: Running as root Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: Using remote: arcam-irdac2. Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 05 15:25:20 volumio sudo[6236]: pam_unix(sudo:session): session closed for user root Jul 05 15:25:20 volumio volumio[1058]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 05 15:25:20 volumio sudo[6252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 05 15:25:20 volumio sudo[6252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 15:25:20 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 05 15:25:20 volumio sudo[6252]: pam_unix(sudo:session): session closed for user root Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Notice: accepted new client on /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: accepted new client on /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: [lirc] protocol is enabled Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Info: [lirc] protocol is enabled Jul 05 15:25:20 volumio volumio[1058]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 2 of 20 Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 2 of 20 Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 2 of 20 Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lircd service status: active Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Notice: accepted new client on /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Info: removed client Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lircd service status: active Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] Error: connect EISCONN /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: accepted new client on /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Info: removed client Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lircd service status: active Jul 05 15:25:20 volumio lircd[6248]: lircd-0.10.1[6248]: Notice: accepted new client on /var/run/lirc/lircd Jul 05 15:25:20 volumio lircd-0.10.1[6248]: Notice: accepted new client on /var/run/lirc/lircd Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] Connected to lirc socket Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] Connected to lirc socket Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] Connected to lirc socket Jul 05 15:25:20 volumio volumio[1058]: info: [IrActivityLed] lirc socket connect: attempt 3 of 20 Jul 05 15:25:20 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 15:25:20 volumio volumio[1058]: Error: Unable to resolve or reject the same promise twice Jul 05 15:25:20 volumio volumio[1058]: at Promise.resolve (/data/plugins/system_hardware/iractivityled/node_modules/kew/kew.js:140:43) Jul 05 15:25:20 volumio volumio[1058]: at Timeout._onTimeout (/data/plugins/system_hardware/iractivityled/index.js:95:26) Jul 05 15:25:20 volumio volumio[1058]: at listOnTimeout (internal/timers.js:554:17) Jul 05 15:25:20 volumio volumio[1058]: at processTimers (internal/timers.js:497:7) Jul 05 15:25:20 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 15:25:20 volumio sudo[6272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 15:24 Jul 05 15:25:20 volumio sudo[6272]: 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="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Jun 2025 03:18:50 PM CEST" VOLUMIO_VERSION="3.818" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d5105a50d571ff4623988fe02d73334b"