May 25 14:11:28 smartradio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 14:11:28 smartradio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 14:11:28 smartradio systemd[1]: setdatetime-helper.service: Consumed 1.721s CPU time. May 25 14:11:28 smartradio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 25 14:11:28 smartradio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 25 14:11:28 smartradio nmbd[1042]: [2026/05/25 14:11:28.091239, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 25 14:11:28 smartradio nmbd[1042]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.47 for name WORKGROUP<1d>. May 25 14:11:28 smartradio nmbd[1042]: This response was from IP 192.168.2.67, reporting an IP address of 192.168.2.67. May 25 14:11:28 smartradio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 25 14:11:28 smartradio systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 25 14:11:28 smartradio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: === SNM TRANSITION === May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: Previous ethernet state: disconnected May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: New ethernet state: connected May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: Single Network Mode: enabled May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: First start: yes May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: === END TRANSITION === May 25 14:11:28 smartradio wireless.js[682]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 25 14:11:28 smartradio ntpd[981]: IO: Listen normally on 3 eth0 192.168.2.47:123 May 25 14:11:28 smartradio ntpd[981]: IO: new interface(s) found: waking up resolver May 25 14:11:28 smartradio ntpd[981]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 14:11:28 smartradio ntpd[981]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 25 14:11:28 smartradio ntpd[981]: DNS: Pool skipping: 88.99.86.9 May 25 14:11:28 smartradio ntpd[981]: DNS: Pool skipping: 217.144.138.234 May 25 14:11:28 smartradio ntpd[981]: DNS: Pool skipping: 185.16.60.96 May 25 14:11:28 smartradio ntpd[981]: DNS: Pool skipping: 188.68.34.173 May 25 14:11:28 smartradio ntpd[981]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 25 14:11:28 smartradio smbd[1174]: [2026/05/25 14:11:28.864237, 0] ../../source3/smbd/server.c:1741(main) May 25 14:11:28 smartradio smbd[1174]: smbd version 4.17.8-Raspbian started. May 25 14:11:28 smartradio smbd[1174]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 25 14:11:28 smartradio winbindd[1108]: [2026/05/25 14:11:28.989594, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) May 25 14:11:28 smartradio winbindd[1108]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'SMARTRADIO' failed 'NT_STATUS_IO_TIMEOUT' May 25 14:11:29 smartradio systemd[1]: Started smbd.service - Samba SMB Daemon. May 25 14:11:29 smartradio sudo[1197]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 14:11:29 smartradio sudo[1197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:11:29 smartradio sudo[1197]: pam_unix(sudo:session): session closed for user root May 25 14:11:29 smartradio sudo[1199]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 14:11:29 smartradio sudo[1199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:11:29 smartradio ntpd[981]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 14:11:30 smartradio ntpd[981]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 131.188.3.222 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 134.60.111.110 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 89.58.3.251 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 94.130.23.46 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 2a03:4000:4f:9c:5852:52ff:fe47:3970 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 2a01:4f8:161:f003::2 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 2003:a:42b:e400::2 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool skipping: 2a02:8106:21:9400::4 May 25 14:11:30 smartradio ntpd[981]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 25 14:11:30 smartradio sudo[1199]: pam_unix(sudo:session): session closed for user root May 25 14:11:30 smartradio wireless.js[682]: WIRELESS.JS - INFO: Cleaning previous... May 25 14:11:30 smartradio sudo[1203]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 25 14:11:30 smartradio sudo[1203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:11:30 smartradio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 14:11:30 smartradio sudo[1203]: pam_unix(sudo:session): session closed for user root May 25 14:11:30 smartradio wireless.js[682]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 25 14:11:30 smartradio wireless.js[682]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 5ms May 25 14:11:30 smartradio wireless.js[682]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:8c:c0:0e) May 25 14:11:30 smartradio sudo[1211]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 25 14:11:30 smartradio sudo[1211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:30 smartradio sudo[1211]: pam_unix(sudo:session): session closed for user root May 25 14:11:30 smartradio sudo[1219]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 25 14:11:30 smartradio sudo[1219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:30 smartradio winbindd[1147]: [2026/05/25 14:11:30.507246, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) May 25 14:11:30 smartradio winbindd[1147]: Could not write result May 25 14:11:30 smartradio ntpd[981]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 14:11:30 smartradio ntpd[981]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool taking: 185.232.69.65 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool taking: 93.241.86.156 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool taking: 162.159.200.1 May 25 14:11:30 smartradio ntpd[981]: DNS: Pool taking: 131.188.3.220 May 25 14:11:30 smartradio ntpd[981]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 25 14:11:30 smartradio sudo[1219]: pam_unix(sudo:session): session closed for user root May 25 14:11:30 smartradio wireless.js[682]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE May 25 14:11:30 smartradio sudo[1227]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE May 25 14:11:30 smartradio sudo[1227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:30 smartradio sudo[1227]: pam_unix(sudo:session): session closed for user root May 25 14:11:30 smartradio wireless.js[682]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE May 25 14:11:31 smartradio wireless.js[682]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability May 25 14:11:31 smartradio wireless.js[682]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) May 25 14:11:31 smartradio wireless.js[682]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active May 25 14:11:31 smartradio sudo[1236]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 25 14:11:31 smartradio sudo[1236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:11:31 smartradio sudo[1236]: pam_unix(sudo:session): session closed for user root May 25 14:11:31 smartradio sudo[1239]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 14:11:31 smartradio sudo[1239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:11:31 smartradio sudo[1239]: pam_unix(sudo:session): session closed for user root May 25 14:11:31 smartradio wpa_supplicant[1242]: Successfully initialized wpa_supplicant May 25 14:11:31 smartradio ntpd[981]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 14:11:31 smartradio ntpd[981]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 25 14:11:31 smartradio ntpd[981]: DNS: Pool taking: 193.203.3.170 May 25 14:11:31 smartradio ntpd[981]: DNS: Pool taking: 195.128.102.95 May 25 14:11:31 smartradio ntpd[981]: DNS: Pool taking: 129.70.132.36 May 25 14:11:31 smartradio ntpd[981]: DNS: Pool taking: 85.121.52.237 May 25 14:11:31 smartradio ntpd[981]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 25 14:11:31 smartradio wpa_supplicant[1245]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 25 14:11:31 smartradio wireless.js[682]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3420ms May 25 14:11:31 smartradio wireless.js[682]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable May 25 14:11:32 smartradio wireless.js[682]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 25 14:11:32 smartradio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 25 14:11:32 smartradio systemd[1]: Started wireless.service - Wireless Services. May 25 14:11:32 smartradio systemd[1]: Started volumio.service - Volumio Backend Module. May 25 14:11:32 smartradio systemd[1]: Started firststart.service - First Start. May 25 14:11:32 smartradio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 25 14:11:32 smartradio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. May 25 14:11:32 smartradio firststart.sh[1266]: Volumio first start configuration script May 25 14:11:32 smartradio firststart.sh[1266]: Configuring unconfigured packages May 25 14:11:32 smartradio systemd[1]: Received SIGRTMIN+21 from PID 306 (plymouthd). May 25 14:11:32 smartradio systemd[1]: Received SIGRTMIN+21 from PID 306 (plymouthd). May 25 14:11:32 smartradio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 25 14:11:32 smartradio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 25 14:11:32 smartradio systemd[1]: Started getty@tty1.service - Getty on tty1. May 25 14:11:32 smartradio systemd[1]: Reached target getty.target - Login Prompts. May 25 14:11:32 smartradio systemd[1]: Reached target multi-user.target - Multi-User System. May 25 14:11:32 smartradio systemd[1]: Reached target graphical.target - Graphical Interface. May 25 14:11:32 smartradio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 25 14:11:32 smartradio volumio-cpu-tweak[1268]: Setting RT Priority for mpd May 25 14:11:32 smartradio volumio-cpu-tweak[1285]: pid 35's current scheduling policy: SCHED_OTHER May 25 14:11:32 smartradio volumio-cpu-tweak[1285]: pid 35's current scheduling priority: 0 May 25 14:11:32 smartradio volumio-cpu-tweak[1268]: Setting MPD Affinity May 25 14:11:32 smartradio volumio-cpu-tweak[1286]: pid 3's current affinity mask: f May 25 14:11:32 smartradio volumio-cpu-tweak[1268]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 25 14:11:32 smartradio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 25 14:11:32 smartradio volumio-cpu-tweak[1268]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 25 14:11:32 smartradio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 25 14:11:32 smartradio systemd[1]: Startup finished in 15.797s (kernel) + 16.499s (userspace) = 32.296s. May 25 14:11:32 smartradio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. May 25 14:11:32 smartradio firststart.sh[1266]: Creating /var/log/samba/cores folder May 25 14:11:32 smartradio firststart.sh[1266]: Creating /boot/userconfig.txt May 25 14:11:32 smartradio firststart.sh[1266]: Removing default SSH host keys May 25 14:11:32 smartradio firststart.sh[1292]: rm: cannot remove '/etc/ssh/ssh_host_*': No such file or directory May 25 14:11:32 smartradio systemd[1]: firststart.service: Main process exited, code=exited, status=1/FAILURE May 25 14:11:32 smartradio systemd[1]: firststart.service: Failed with result 'exit-code'. May 25 14:11:34 smartradio volumio-remote-updater[681]: [2026-05-25 14:11:34] [info] asio async_connect error: asio.system:111 (Connection refused) May 25 14:11:34 smartradio volumio-remote-updater[681]: [2026-05-25 14:11:34] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 25 14:11:34 smartradio volumio-remote-updater[681]: [2026-05-25 14:11:34] [error] handle_connect error: Connection refused May 25 14:11:37 smartradio ntpd[981]: CLOCK: time stepped by 0.430813 May 25 14:11:37 smartradio ntpd[981]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 25 14:11:37 smartradio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 25 14:11:37 smartradio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:11:37 smartradio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:11:37 smartradio upmpdcli[1293]: Could not open config: /tmp/upmpdcli.conf May 25 14:11:37 smartradio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 14:11:37 smartradio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 14:11:38 smartradio volumio[1265]: info: ------------------------------------------- May 25 14:11:38 smartradio volumio[1265]: info: ----- Volumio3 ---- May 25 14:11:38 smartradio volumio[1265]: info: ------------------------------------------- May 25 14:11:38 smartradio volumio[1265]: info: ----- System startup ---- May 25 14:11:38 smartradio volumio[1265]: info: ------------------------------------------- May 25 14:11:40 smartradio volumio[1265]: info: MYVOLUMIO Environment detected May 25 14:11:41 smartradio volumio[1265]: info: Plugin folders cleanup May 25 14:11:41 smartradio volumio[1265]: info: Scanning into folder /volumio/app/plugins/ May 25 14:11:41 smartradio volumio[1265]: info: Scanning category audio_interface May 25 14:11:41 smartradio volumio[1265]: info: Scanning category miscellanea May 25 14:11:41 smartradio volumio[1265]: info: Scanning category music_service May 25 14:11:41 smartradio volumio[1265]: info: Scanning category plugins.json May 25 14:11:41 smartradio volumio[1265]: info: Scanning category system_controller May 25 14:11:41 smartradio volumio[1265]: info: Scanning category user_interface May 25 14:11:41 smartradio volumio[1265]: info: Scanning into folder /data/plugins/ May 25 14:11:41 smartradio volumio[1265]: info: Scanning category audio_interface May 25 14:11:41 smartradio volumio[1265]: info: Scanning category music_service May 25 14:11:41 smartradio volumio[1265]: info: Scanning category system_controller May 25 14:11:41 smartradio volumio[1265]: info: Scanning category system_hardware May 25 14:11:41 smartradio volumio[1265]: info: Plugin folders cleanup completed May 25 14:11:41 smartradio volumio[1265]: info: ------------------------------------------- May 25 14:11:41 smartradio volumio[1265]: info: ----- Core plugins startup ---- May 25 14:11:41 smartradio volumio[1265]: info: ------------------------------------------- May 25 14:11:41 smartradio volumio[1265]: info: Loading plugins from folder /volumio/app/plugins/ May 25 14:11:41 smartradio volumio[1265]: info: Adding plugin upnp to MyMusic Plugins May 25 14:11:41 smartradio volumio[1265]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 14:11:41 smartradio volumio[1265]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 14:11:41 smartradio volumio[1265]: info: Loading plugins from folder /data/plugins/ May 25 14:11:41 smartradio volumio[1265]: info: Loading plugin "system"... May 25 14:11:41 smartradio volumio[1265]: info: Loading plugin "appearance"... May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "network"... May 25 14:11:44 smartradio volumio[1265]: info: Refreshing Cached IP Addresses May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "services"... May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "volumio5onboarding"... May 25 14:11:44 smartradio sudo[1307]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 14:11:44 smartradio sudo[1307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:44 smartradio sudo[1316]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 14:11:44 smartradio sudo[1316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "alsa_controller"... May 25 14:11:44 smartradio sudo[1309]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 14:11:44 smartradio sudo[1307]: pam_unix(sudo:session): session closed for user root May 25 14:11:44 smartradio sudo[1309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:44 smartradio sudo[1309]: pam_unix(sudo:session): session closed for user root May 25 14:11:44 smartradio volumio-remote-updater[681]: [2026-05-25 14:11:44] [connect] Successful connection May 25 14:11:44 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "wizard"... May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "networkfs"... May 25 14:11:44 smartradio volumio[1265]: info: Starting Udev Watcher for removable devices May 25 14:11:44 smartradio volumio[1265]: info: Ignoring mount for partition: boot May 25 14:11:44 smartradio volumio[1265]: info: Ignoring mount for partition: volumio May 25 14:11:44 smartradio volumio[1265]: info: Ignoring mount for partition: volumio_data May 25 14:11:44 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 14:11:44 smartradio volumio[1265]: info: Loading plugin "volumio_command_line_client"... May 25 14:11:45 smartradio sudo[1341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=moritz,password=mO2rI8tZ04-qnAp!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.10/public\\music /mnt/NAS/music May 25 14:11:45 smartradio sudo[1341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:11:45 smartradio volumio[1265]: info: Loading plugin "upnp"... May 25 14:11:45 smartradio volumio[1265]: info: [1779711105081] Starting Upmpd Daemon May 25 14:11:45 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 14:11:45 smartradio volumio[1265]: info: Loading plugin "my_music"... May 25 14:11:45 smartradio volumio[1265]: info: Loading plugin "mpd"... May 25 14:11:45 smartradio kernel: netfs: FS-Cache loaded May 25 14:11:45 smartradio sudo[1316]: pam_unix(sudo:session): session closed for user root May 25 14:11:45 smartradio kernel: Key type cifs.spnego registered May 25 14:11:45 smartradio kernel: Key type cifs.idmap registered May 25 14:11:45 smartradio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 25 14:11:45 smartradio kernel: CIFS: Attempting to mount //192.168.2.10/public/music May 25 14:11:46 smartradio sudo[1341]: pam_unix(sudo:session): session closed for user root May 25 14:11:46 smartradio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 25 14:11:46 smartradio systemd[1]: e2scrub_all.service: Deactivated successfully. May 25 14:11:46 smartradio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 25 14:11:46 smartradio volumio[1265]: info: Loading plugin "upnp_browser"... May 25 14:11:49 smartradio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 25 14:11:50 smartradio volumio[1265]: info: Starting UPNP Browser May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "alarm-clock"... May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "airplay_emulation"... May 25 14:11:50 smartradio volumio[1265]: info: Starting Shairport Sync May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "last_100"... May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "webradio"... May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "i2s_dacs"... May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "volumiodiscovery"... May 25 14:11:50 smartradio volumio[1265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 14:11:50 smartradio node[1265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 14:11:50 smartradio volumio[1265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 14:11:50 smartradio node[1265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 14:11:50 smartradio volumio[1265]: *** WARNING *** For more information see May 25 14:11:50 smartradio node[1265]: *** WARNING *** For more information see May 25 14:11:50 smartradio volumio[1265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 14:11:50 smartradio node[1265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 14:11:50 smartradio volumio[1265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 14:11:50 smartradio node[1265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 14:11:50 smartradio volumio[1265]: *** WARNING *** For more information see May 25 14:11:50 smartradio node[1265]: *** WARNING *** For more information see May 25 14:11:50 smartradio volumio[1265]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 14:11:50 smartradio volumio[1265]: info: Discovery: Started advertising with name: smartradio May 25 14:11:50 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 14:11:50 smartradio volumio[1265]: info: Loading plugin "spop"... May 25 14:11:52 smartradio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 25 14:11:52 smartradio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:11:52 smartradio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:11:52 smartradio upmpdcli[1381]: Could not open config: /tmp/upmpdcli.conf May 25 14:11:52 smartradio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 14:11:52 smartradio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 14:11:53 smartradio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 25 14:11:55 smartradio volumio[1265]: info: Loading plugin "autostart"... May 25 14:11:56 smartradio volumio[1265]: info: Applying required configuration parameters for plugin autostart May 25 14:11:56 smartradio volumio[1265]: info: AutoStart - onVolumioStart - read config.json May 25 14:11:56 smartradio volumio[1265]: info: Loading plugin "gpio-buttons"... May 25 14:11:56 smartradio volumio[1265]: gpiox_arm32 V2.2.1 May 25 14:11:57 smartradio volumio[1265]: info: gpio-buttons - GPIO-Buttons initialized May 25 14:11:57 smartradio volumio[1265]: info: Loading plugin "outputs"... May 25 14:11:57 smartradio volumio[1265]: info: Loading plugin "albumart"... May 25 14:11:57 smartradio volumio[1265]: info: Plugin example_plugin is not enabled May 25 14:11:57 smartradio volumio[1265]: info: Loading plugin "inputs"... May 25 14:11:57 smartradio volumio[1265]: info: Loading plugin "updater_comm"... May 25 14:11:58 smartradio volumio[1265]: info: Plugin mpdemulation is not enabled May 25 14:11:58 smartradio volumio[1265]: info: Loading plugin "rest_api"... May 25 14:11:58 smartradio volumio[1265]: info: Loading plugin "websocket"... May 25 14:11:58 smartradio volumio[1265]: info: Starting Socket.io Server version 1.7.4 May 25 14:11:58 smartradio volumio[1265]: info: Plugin fusiondsp is not enabled May 25 14:11:58 smartradio volumio[1265]: info: Loading plugin "gpio_control"... May 25 14:11:58 smartradio volumio[1265]: gpiox_arm32 V2.2.1 May 25 14:11:59 smartradio volumio-remote-updater[681]: [2026-05-25 14:11:59] [connect] Successful connection May 25 14:11:59 smartradio volumio[1399]: Forking 3 albumart workers May 25 14:12:01 smartradio volumio[1265]: info: Applying required configuration parameters for plugin gpio_control May 25 14:12:01 smartradio volumio[1265]: info: Loading plugin "rotaryencoder2"... May 25 14:12:06 smartradio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 25 14:12:07 smartradio volumio[1265]: info: Loading i18n strings for locale de May 25 14:12:07 smartradio volumio[1265]: Updating browse sources language May 25 14:12:07 smartradio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 25 14:12:07 smartradio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:12:07 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 14:12:08 smartradio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:12:08 smartradio upmpdcli[1474]: Could not open config: /tmp/upmpdcli.conf May 25 14:12:08 smartradio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 14:12:08 smartradio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 14:12:08 smartradio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 14:12:08 smartradio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 14:12:08 smartradio systemd[1]: setdatetime-helper.service: Consumed 1.580s CPU time. May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::initPlayerControls May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 14:12:10 smartradio volumio[1265]: Express server listening on port 3000 May 25 14:12:10 smartradio volumio[1265]: [Metrics] WebUI: 33s 6.20ms May 25 14:12:10 smartradio volumio[1265]: info: CoreStateMachine::resetVolumioState May 25 14:12:10 smartradio volumio[1265]: info: CoreStateMachine::getcurrentVolume May 25 14:12:10 smartradio volumio[1265]: info: CoreCommandRouter::volumioRetrievevolume May 25 14:12:10 smartradio sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 14:12:10 smartradio sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:10 smartradio sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 14:12:10 smartradio sudo[1484]: pam_unix(sudo:session): session closed for user root May 25 14:12:10 smartradio sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:10 smartradio sudo[1486]: pam_unix(sudo:session): session closed for user root May 25 14:12:10 smartradio volumio[1265]: info: Volumio Network Manager: Network status updated: 1 May 25 14:12:12 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 25 14:12:12 smartradio volumio[1265]: info: VolumeController:: Volume=100 Mute =false May 25 14:12:12 smartradio volumio[1265]: info: CoreStateMachine::pushState May 25 14:12:12 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:12 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 14:12:12 smartradio volumio[1265]: info: CoreCommandRouter::volumioPushState May 25 14:12:12 smartradio volumio[1265]: info: CoreStateMachine::updateTrackBlock May 25 14:12:12 smartradio volumio[1265]: info: CorePlayQueue::getTrackBlock May 25 14:12:12 smartradio volumio[1265]: info: CoreCommandRouter::volumioRetrievevolume May 25 14:12:13 smartradio volumio[1265]: info: Reloading queue from file May 25 14:12:13 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:13 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 25 14:12:13 smartradio volumio[1265]: info: CoreStateMachine::setRepeat null single undefined May 25 14:12:13 smartradio volumio[1265]: info: CoreStateMachine::pushState May 25 14:12:13 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:13 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 14:12:13 smartradio volumio[1265]: info: CoreCommandRouter::volumioPushState May 25 14:12:13 smartradio volumio[1265]: info: CoreStateMachine::setRandom null May 25 14:12:13 smartradio volumio[1265]: info: CoreStateMachine::pushState May 25 14:12:13 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:13 smartradio volumio[1265]: info: CoreCommandRouter::volumioPushState May 25 14:12:13 smartradio volumio[1265]: info: Setting Device type: Raspberry PI May 25 14:12:14 smartradio volumio[1410]: Starting albumart workers May 25 14:12:14 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 25 14:12:14 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47:3000 from 192.168.2.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 25 14:12:14 smartradio volumio[1265]: info: Completed loading Core Plugins May 25 14:12:14 smartradio volumio[1265]: info: Preparing to generate the ALSA configuration file May 25 14:12:14 smartradio volumio-remote-updater[681]: [2026-05-25 14:12:14] [connect] Successful connection May 25 14:12:15 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 25 14:12:15 smartradio volumio[1265]: info: VolumeController:: Volume=100 Mute =false May 25 14:12:15 smartradio volumio[1265]: info: CoreStateMachine::pushState May 25 14:12:15 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:15 smartradio volumio[1265]: info: CoreCommandRouter::volumioPushState May 25 14:12:15 smartradio volumio[1265]: info: Discovery: adding c5b2cbf6-9d75-4c39-a4a2-de937bad4913 May 25 14:12:15 smartradio volumio[1265]: info: Discovery: Found device smartradio May 25 14:12:15 smartradio volumio[1265]: info: CoreCommandRouter::volumioGetState May 25 14:12:15 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:15 smartradio volumio[1409]: Starting albumart workers May 25 14:12:15 smartradio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 14:12:15 smartradio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:15 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 25 14:12:15 smartradio volumio[1265]: info: Discovery: this is already registered, c5b2cbf6-9d75-4c39-a4a2-de937bad4913 May 25 14:12:15 smartradio volumio[1265]: info: Discovery: Found device smartradio May 25 14:12:15 smartradio volumio[1265]: info: CoreCommandRouter::volumioGetState May 25 14:12:15 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:15 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47:3000 from 192.168.2.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 25 14:12:15 smartradio volumio[1265]: info: Asound.conf file unchanged, so no further update is needed May 25 14:12:15 smartradio volumio[1265]: info: Output device has changed, restarting MPD May 25 14:12:16 smartradio volumio[1265]: info: Output device has changed, restarting Shairport Sync May 25 14:12:16 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:16 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:16 smartradio volumio[1411]: Starting albumart workers May 25 14:12:16 smartradio sudo[1520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 14:12:16 smartradio sudo[1520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:16 smartradio sudo[1522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 14:12:16 smartradio sudo[1520]: pam_unix(sudo:session): session closed for user root May 25 14:12:16 smartradio sudo[1522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:16 smartradio volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 14:12:16 smartradio volumio[1265]: info: ___________ START PLUGINS ___________ May 25 14:12:16 smartradio volumio[1265]: info: ControllerMpd::onStart: Initializing MPD May 25 14:12:16 smartradio volumio[1265]: info: Creating MPD Configuration file May 25 14:12:16 smartradio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 14:12:16 smartradio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 14:12:17 smartradio volumio[1265]: info: [1779711137024] CoreMusicLibrary::Adding element Medienserver May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 14:12:17 smartradio sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 25 14:12:17 smartradio sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:17 smartradio sudo[1533]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 14:12:17 smartradio sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:12:17 smartradio sudo[1537]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 25 14:12:17 smartradio sudo[1533]: pam_unix(sudo:session): session closed for user root May 25 14:12:17 smartradio volumio[1265]: info: UPNP Browser: Client initialized successfully May 25 14:12:17 smartradio sudo[1534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 14:12:17 smartradio sudo[1534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:17 smartradio sudo[1534]: pam_unix(sudo:session): session closed for user root May 25 14:12:17 smartradio sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 14:12:17 smartradio sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:17 smartradio volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 14:12:17 smartradio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:17 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:17 smartradio sudo[1530]: pam_unix(sudo:session): session closed for user root May 25 14:12:18 smartradio systemd[1]: mpd.service: Deactivated successfully. May 25 14:12:18 smartradio systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 14:12:18 smartradio systemd[1]: mpd.socket: Deactivated successfully. May 25 14:12:18 smartradio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 14:12:18 smartradio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 14:12:18 smartradio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 14:12:18 smartradio volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 14:12:18 smartradio systemd[1]: Starting mpd.service - Music Player Daemon... May 25 14:12:18 smartradio volumio[1265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 14:12:18 smartradio volumio[1265]: info: [1779711138251] CoreMusicLibrary::Adding element Last_100 May 25 14:12:18 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 14:12:18 smartradio volumio[1265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 14:12:18 smartradio volumio[1265]: info: [1779711138375] CoreMusicLibrary::Adding element Webradio May 25 14:12:18 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 14:12:18 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 14:12:18 smartradio sudo[1557]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 14:12:18 smartradio sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 14:12:18 smartradio sudo[1558]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 25 14:12:18 smartradio sudo[1557]: pam_unix(sudo:session): session closed for user root May 25 14:12:18 smartradio volumio[1265]: info: Initializing BBC Radios May 25 14:12:18 smartradio volumio5-onboarding[1542]: time=2026-05-25T14:12:18.766+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z May 25 14:12:19 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 14:12:19 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:19 smartradio volumio[1265]: info: Creating Spotify config file May 25 14:12:19 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:23 smartradio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. May 25 14:12:23 smartradio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:12:23 smartradio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 14:12:23 smartradio sudo[1511]: pam_unix(sudo:session): session closed for user root May 25 14:12:25 smartradio volumio[1265]: info: AutoStart - onStart May 25 14:12:25 smartradio volumio[1265]: info: gpio-buttons - Initializing GPIO triggers... May 25 14:12:25 smartradio volumio[1265]: info: gpio-buttons - ✅ GPIO26 configured for playPause May 25 14:12:25 smartradio volumio[1265]: info: [ROTARYENCODER2] loadI18nStrings: /data/plugins/system_hardware/rotaryencoder2/i18n/strings_de.json May 25 14:12:25 smartradio volumio[1265]: info: [ROTARYENCODER2] loadI18nStrings: loaded: May 25 14:12:25 smartradio volumio[1265]: info: [ROTARYENCODER2]{"ROTARYENCODER2":{"CONFIG":"Rotary Encoder II Plugin Konfiguration","SAVE":"Speichern","ENCODER0":"Drehgeber 1","D_ENCODER0":"Einstellungen für den ersten Drehgeber.","ENCODER1":"Drehgeber 2","D_ENCODER1":"Einstellungen für den zweiten Drehgeber.","ENCODER2":"Drehgeber 3","D_ENCODER2":"Einstellungen für den dritten Drehgeber.","ROTARYTYPE":"Perioden pro Schritt","D_ROTARYTYPE":"Perioden pro Schritt (Voll: A und B ganze Periode, Halb: A und B halbe Periode, Viertel: A oder B halbe Periode","FULL":"Volle Perioden Typ","HALF":"Halbe Perioden Typ","QUARTER":"Viertel Perioden Typ","PINA":"Anschluss A GPIO","D_PINA":"GPIO Ein-/Ausgang der mit dem ersten Anschluss des Drehgebers verbunden ist.","PINB":"Anschluss B GPIO","D_PINB":"GPIO Ein-/Ausgang der mit dem zweiten Anschluss des Drehgebers verbunden ist.","DIALACTION":"Drehfunktion","D_DIALACTION":"Funktion, die durch Drehen des Drehgebers ausgelöst wird. Die Funktion 'Websocket Nachricht senden' kann z.B. verwendet werden, um Websock Kommandos an andere Plugins zu senden. (Siehe https://volumio.github.io/docs/API/WebSocket_APIs.html Abschnitt 'CallMethod on Plugin' für weitere Informationen.)","DOTS":"...","VOLUME":"Lautstärke","SKIP":"Titel vor/zurück","SEEK":"Suche im Titel","SCROLL":"Scrollen","EMIT":"Websocket Nachricht senden","TOGGLEFUSION":"Fusion DSP an/aus","SOCKCMDCCW":"Kommando GUZS","SOCKDATACCW":"Daten GUZS","SOCKCMDCW":"Kommando UZS","SOCKDATACW":"Daten UZS","SOCKCMD":"Kommando","SOCKDATA":"Daten","D_SOCKCMD":"Kommandos, die via Websocket gesendet werden sollen.","D_SOCKDATA":"Daten, die zusammen mit dem Websocket Kommando gesendet werden.","PINPUSH":"Taster GPIO","D_PINPUSH":"GPIO Ein-/Ausgang der mit dem Taster des Drehgebers verbunden ist. 0 oder leer zum deaktivieren.","PUSHACTION":"Funktion Tastendruck","D_PUSHACTION":"Funktion, die durch das Drücken des Tasters ausgelöst wird.","LONGPUSHACTION":"Funktion langer Tastendruck","D_LONGPUSHACTION":"Funktion, die durch das lange Drücken des Tasters ausgelöst wird.","DOUBLEPUSHACTION":"Funktion doppelter Tastendruck","D_DOUBLEPUSHACTION":"Funktion, die durch das zweifache Drücken des Tasters ausgelöst wird","PINPUSHDEBOUNCE":"Entprell-Zeit (ms)","D_PINPUSHDEBOUNCE":"Entprell-Zeit für den Taster. Falls der Taster Hardware-entprellt ist, 0 setzen.","PUSHSTATE":"Taster-Logikpegel low-aktiv","D_PUSHSTATE":"Aktivieren, wenn der Logikpegel am GPIO bei gedrücktem Taster 'low' ist.","PINPUSHDELAY":"Verzögerung Tastendruck lang (ms)","D_PINPUSHDEDELAY":"Zeit in ms, die man den Taster gedrückt halten muss, um die Aktion für den langen Tastendruck auszulösen","PINDOUBLEPUSHDELAY":"Maximale Zeit zwischen doppelten Tastendrücken (ms)","D_PINDOUBLEPUSHDEDELAY":"Zeit in ms, innerhalb der zwei Tastendrücke passieren müssen","SET_DEBUG":"Fehlerdiagnose Einstellungen","D_SET_DEBUG":"Einstellungen zur Unterstützung der Fehlersuche.","PLAY":"Wiedergabe starten","PAUSE":"Pause","PLAYPAUSE":"Pause an/aus","STOP":"Stop","REPEAT":"Wiederholen","RANDOM":"Zufällige Wiedergabe","CLEARQUEUE":"Wiedergabeliste löschen","MUTE":"Stumm","UNMUTE":"Stumm aufheben","TOGGLEMUTE":"Stumm an/aus","SHUTDOWN":"System herunterfahren","REBOOT":"System neu starten","RESTARTAPP":"Applikation neu starten","DUMPLOG":"Log Datei abspeichern","LOGGING":"Log","LOGGINGJSON":"JSON Objects mitloggen","D_LOGGING":"Aktiviert das Schreiben von Log-Nachrichten. Fehlermeldungen werden immer geschrieben.","D_LOGGINGJSON":"Aktiviert das mitschreiben von JSON Objekten im log. Macht die Logs länger.","TOAST_START_SUCCESS":"Plugin erfolgreich gestartet.","TOAST_STOP_SUCCESS":"Plugin erfolgreich angehalten.","TOAST_START_FAIL":"Start des Plugins fehlgeschlagen.","TOAST_STOP_FAIL":"Anhalten des Plugins fehlgeschlagen.","TOAST_GPIO_BLOCKED":"GPIO Pin nicht frei","TOAST_MSG_OVERLAY_BLOCKING":"Blockiert durch weiteres Overlay.","TOAST_SAVE_SUCCESS":"Speichern erfolgreich","TOAST_MSG_SAVE":"Einstellungen für Drehgeber ","TOAST_DEBUG_SAVE":"Einstellungen für Fehlersuche","TOAST_SAVE_FAIL":"Speichern gescheitert","TOAST_WRONG_PARAMETER":"Fehler in Einstellungen","TOAST_NEEDS_INTEGER":"GPIO Pins müssen Ganzzahlen sein.","TOAST_PINS_DIFFERENT":"GPIO Pins für Anschlüsse A/B/Taster müssen verschieden sein.","TOAST_PINS_BLOCKED":"Pins werden in einem der anderen Drehgeber verwendet","TOAST_NO_TYPE":"Bitte 'Periode pro Schritt' angeben!","TOAST_KILL_HANDLE_FAIL":"Konnte Rotary Resourcen nicht freigeben. Richtige GPIOs gewählt? Bitte korrigieren und System neu starten!","TOAST_ERR_FROM_STREAM":"Fehler über den Stream vom Rotary, bitte Hardware und GPIO Settings prüfen.","TOAST_ERR_ACT_ROTARY_FAILED":"Konfigurieren des Drehgebers fehlgeschlagen.","TOAST_ERR_ADD_OVERLAY_FAILED":"Hinzufügen gescheitert für Overlay: ","TOAST_ERR_ATTACH_LISTENER_FAILED":"Anhängen eines Listeners für DTO fehlgeschlagen"}} May 25 14:12:25 smartradio volumio[1265]: info: [ROTARYENCODER2] onStart: Config loaded: May 25 14:12:26 smartradio volumio[1265]: info: [ROTARYENCODER2]{"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"enabled0":{"type":"boolean","value":true},"rotaryType0":{"type":"number","value":4},"pinA0":{"type":"string","value":"2"},"pinB0":{"type":"string","value":"3"},"dialAction0":{"type":"number","value":2},"socketCmdCW0":{"type":"string","value":""},"socketDataCW0":{"type":"string","value":""},"socketCmdCCW0":{"type":"string","value":""},"socketDataCCW0":{"type":"string","value":""},"pinPush0":{"type":"number","value":25},"pinPushDebounce0":{"type":"number","value":10},"pushState0":{"type":"boolean","value":true},"pushAction0":{"type":"number","value":2},"socketCmdPush0":{"type":"string","value":""},"socketDataPush0":{"type":"string","value":""},"longPushAction0":{"type":"number","value":1},"socketCmdLongPush0":{"type":"string","value":""},"socketDataLongPush0":{"type":"string","value":""},"delayLongPush0":{"type":"string","value":"1500"},"doublePushAction0":{"type":"number","value":4},"socketCmdDoublePush0":{"type":"string","value":""},"socketDataDoublePush0":{"type":"string","value":""},"delayDoublePush0":{"type":"string","value":"700"},"enabled1":{"type":"boolean","value":true},"rotaryType1":{"type":"number","value":2},"pinA1":{"type":"string","value":"5"},"pinB1":{"type":"string","value":"6"},"dialAction1":{"type":"number","value":1},"socketCmdCW1":{"type":"string","value":""},"socketDataCW1":{"type":"string","value":""},"socketCmdCCW1":{"type":"string","value":""},"socketDataCCW1":{"type":"string","value":""},"pinPush1":{"type":"number","value":0},"pinPushDebounce1":{"type":"number","value":0},"pushState1":{"type":"boolean","value":true},"pushAction1":{"type":"number","value":3},"socketCmdPush1":{"type":"string","value":""},"socketDataPush1":{"type":"string","value":""},"longPushAction1":{"type":"number","value":0},"socketCmdLongPush1":{"type":"string","value":""},"socketDataLongPush1":{"type":"string","value":""},"delayLongPush1":{"type":"string","value":"1500"},"doublePushAction1":{"type":"number","value":0},"socketCmdDoublePush1":{"type":"string","value":""},"socketDataDoublePush1":{"type":"string","value":""},"delayDoublePush1":{"type":"string","value":"700"},"enabled2":{"type":"boolean","value":true},"rotaryType2":{"type":"number","value":4},"pinA2":{"type":"string","value":"22"},"pinB2":{"type":"string","value":"27"},"dialAction2":{"type":"number","value":2},"socketCmdCW2":{"type":"string","value":""},"socketDataCW2":{"type":"string","value":""},"socketCmdCCW2":{"type":"string","value":""},"socketDataCCW2":{"type":"string","value":""},"pinPush2":{"type":"number","value":0},"pinPushDebounce2":{"type":"number","value":0},"pushState2":{"type":"boolean","value":true},"pushAction2":{"type":"number","value":0},"socketCmdPush2":{"type":"string","value":""},"socketDataPush2":{"type":"string","value":""},"longPushAction2":{"type":"number","value":0},"socketCmdLongPush2":{"type":"string","value":""},"socketDataLongPush2":{"type":"string","value":""},"delayLongPush2":{"type":"string","value":"1500"},"doublePushAction2":{"type":"number","value":0},"socketCmdDoublePush2":{"type":"string","value":""},"socketDataDoublePush2":{"type":"string","value":""},"delayDoublePush2":{"type":"string","value":"700"},"logging":{"type":"boolean","value":true},"loggingJSON":{"type":"boolean","value":true}},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/rotaryencoder2/config.json"} May 25 14:12:26 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayL May 25 14:12:26 smartradio volumio[1265]: info: Volumio Calling Home May 25 14:12:26 smartradio sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 25 14:12:26 smartradio sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:27 smartradio sudo[1621]: pam_unix(sudo:session): session closed for user root May 25 14:12:27 smartradio volumio[1265]: info: gpio-buttons - GPIO-Buttons started May 25 14:12:28 smartradio volumio5-onboarding[1542]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:45664->127.0.0.1:3000: i/o timeout May 25 14:12:28 smartradio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 25 14:12:28 smartradio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 25 14:12:28 smartradio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. May 25 14:12:28 smartradio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. May 25 14:12:29 smartradio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 25 14:12:29 smartradio volumio5-onboarding[1626]: time=2026-05-25T14:12:29.129+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z May 25 14:12:29 smartradio volumio[1265]: info: Discovery: adding ffe6289c-b757-4a66-948a-600735089299 May 25 14:12:29 smartradio volumio[1265]: info: Discovery: Found device lounge May 25 14:12:29 smartradio volumio-remote-updater[681]: [2026-05-25 14:12:29] [connect] Successful connection May 25 14:12:29 smartradio volumio[1265]: info: Discovery: Connecting to remote: 192.168.2.29 May 25 14:12:30 smartradio mpd[1559]: 2026-05-25T14:12:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 14:12:30 smartradio systemd[1]: Started mpd.service - Music Player Daemon. May 25 14:12:30 smartradio sudo[1536]: pam_unix(sudo:session): session closed for user root May 25 14:12:30 smartradio sudo[1522]: pam_unix(sudo:session): session closed for user root May 25 14:12:30 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayL returned: [] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 6 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [{"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4},{"rotaryIdx":0,"type":"gpio-key","pinA":25,"debounce":10,"action":2,"socketCmdPush":"","socketDataPush":"","longPushAction":1,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":4,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20},{"rotaryIdx":1,"type":"rotary-encoder","pinA":5,"pinB":6,"dialAction":1,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":2},{"rotaryIdx":1,"type":"gpio-key","pinA":0,"debounce":0,"action":3,"socketCmdPush":"","socketDataPush":"","longPushAction":0,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":0,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20},{"rotaryIdx":2,"type":"rotary-encoder","pinA":22,"pinB":27,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4},{"rotaryIdx":2,"type":"gpio-key","pinA":0,"debounce":0,"action":0,"socketCmdPush":"","socketDataPush":"","longPushAction":0,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":0,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20}] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 5 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [{"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4},{"rotaryIdx":0,"type":"gpio-key","pinA":25,"debounce":10,"action":2,"socketCmdPush":"","socketDataPush":"","longPushAction":1,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":4,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20},{"rotaryIdx":1,"type":"rotary-encoder","pinA":5,"pinB":6,"dialAction":1,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":2},{"rotaryIdx":1,"type":"gpio-key","pinA":0,"debounce":0,"action":3,"socketCmdPush":"","socketDataPush":"","longPushAction":0,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":0,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20},{"rotaryIdx":2,"type":"rotary-encoder","pinA":22,"pinB":27,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4}] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 4 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [{"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4},{"rotaryIdx":0,"type":"gpio-key","pinA":25,"debounce":10,"action":2,"socketCmdPush":"","socketDataPush":"","longPushAction":1,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":4,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20},{"rotaryIdx":1,"type":"rotary-encoder","pinA":5,"pinB":6,"dialAction":1,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":2},{"rotaryIdx":1,"type":"gpio-key","pinA":0,"debounce":0,"action":3,"socketCmdPush":"","socketDataPush":"","longPushAction":0,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":0,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20}] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 3 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [{"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4},{"rotaryIdx":0,"type":"gpio-key","pinA":25,"debounce":10,"action":2,"socketCmdPush":"","socketDataPush":"","longPushAction":1,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":4,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20},{"rotaryIdx":1,"type":"rotary-encoder","pinA":5,"pinB":6,"dialAction":1,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":2}] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 2 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [{"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4},{"rotaryIdx":0,"type":"gpio-key","pinA":25,"debounce":10,"action":2,"socketCmdPush":"","socketDataPush":"","longPushAction":1,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":4,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20}] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 1 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [{"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4}] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: 0 May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] [] May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] installAllOverlays: end of recursion. May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayAdd May 25 14:12:30 smartradio volumio[1265]: info: [ROTARYENCODER2] {"rotaryIdx":0,"type":"rotary-encoder","pinA":2,"pinB":3,"dialAction":2,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":4} May 25 14:12:30 smartradio volumio[1265]: info: MPD Permissions set May 25 14:12:30 smartradio volumio[1265]: info: MPD Permissions set May 25 14:12:30 smartradio volumio[1265]: info: Upmpdcli Daemon Started May 25 14:12:31 smartradio sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=2 pin_b=3 relative_axis=true steps-per-period=4 May 25 14:12:31 smartradio sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:31 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47:3000 from 192.168.2.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 25 14:12:31 smartradio sudo[1639]: pam_unix(sudo:session): session closed for user root May 25 14:12:31 smartradio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio2 already requested by 3f804000.i2c; cannot claim for rotary@2 May 25 14:12:31 smartradio kernel: pinctrl-bcm2835 3f200000.gpio: error -EINVAL: pin-2 (rotary@2) May 25 14:12:31 smartradio kernel: pinctrl-bcm2835 3f200000.gpio: error -EINVAL: could not request pin 2 (gpio2) from group gpio2 on device pinctrl-bcm2835 May 25 14:12:31 smartradio kernel: rotary-encoder rotary@2: Error applying setting, reverse things back May 25 14:12:31 smartradio volumio[1265]: info: Volumio called home May 25 14:12:31 smartradio volumio[1265]: info: Spotify config file written May 25 14:12:31 smartradio sudo[1652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 14:12:32 smartradio sudo[1652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:32 smartradio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 14:12:32 smartradio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 14:12:32 smartradio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 14:12:32 smartradio go-librespot[1654]: go-librespot daemon starting... May 25 14:12:32 smartradio sudo[1652]: pam_unix(sudo:session): session closed for user root May 25 14:12:32 smartradio go-librespot[1655]: time="2026-05-25T14:12:32+02:00" level=info msg="running go-librespot 0.4.0" May 25 14:12:32 smartradio go-librespot[1655]: time="2026-05-25T14:12:32+02:00" level=debug msg="app state loaded" May 25 14:12:32 smartradio go-librespot[1655]: time="2026-05-25T14:12:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 14:12:33 smartradio go-librespot[1655]: time="2026-05-25T14:12:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 14:12:33 smartradio go-librespot[1655]: time="2026-05-25T14:12:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 14:12:33 smartradio go-librespot[1655]: time="2026-05-25T14:12:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 14:12:33 smartradio go-librespot[1655]: time="2026-05-25T14:12:33+02:00" level=info msg="zeroconf server listening on port 40447" May 25 14:12:33 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="obtained new client token: AAChaRVlSNgFLE+z/HDTq9uEYmYXMmoRqKAVdA4/EY4BhihLZEsGfAeaL8eM8XIIzESgGEJV+qLOBDpMVjGpK+n1GwM3FNzs+7EPhbySwgdWbkjMMwKmts9xVVnrku36JnKc44TOs6GFF558W740TpXq9tJO82apAKPyrH/L+vQLwGrwWSJf56GOW30hhlq4TLfMaD65sWl3wz58oFy42tUCkBRJPQGkZJB00J5KwDYhs1s2DFRPCqRm" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="completed keyexchange" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="completed challenge" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=info msg="authenticated AP" username="48*********************zl" May 25 14:12:34 smartradio volumio[1265]: info: Discovery: Connected to remote: 192.168.2.29 May 25 14:12:34 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayAdd executed: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=2 pin_b=3 relative_axis=true steps-per-period=4 May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=info msg="authenticated Login5" username="48*********************zl" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="initializing zeroconf session" username="48*********************zl" May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="dealer connection opened" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=trace msg="starting accesspoint recv loop" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=trace msg="starting dealer recv loop" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=trace msg="received accesspoint ping" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=debug msg="received connection id: MDgxOGQ2OGMtNzY1...Q0E1RTVGQzdGRQ==" May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 14:12:34 smartradio go-librespot[1655]: time="2026-05-25T14:12:34+02:00" level=trace msg="received accesspoint pong ack" May 25 14:12:34 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 14:12:34 smartradio volumio[1265]: info: No need to fix Spotify hosts May 25 14:12:35 smartradio go-librespot[1655]: time="2026-05-25T14:12:35+02:00" level=debug msg="put connect state because NEW_DEVICE" May 25 14:12:36 smartradio volumio[1265]: error: MPD error: The expression evaluated to a falsy value: May 25 14:12:36 smartradio volumio[1265]: assert.ok(self.idling) May 25 14:12:36 smartradio volumio[1265]: error: The expression evaluated to a falsy value: May 25 14:12:36 smartradio volumio[1265]: assert.ok(self.idling) May 25 14:12:36 smartradio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 25 14:12:36 smartradio volumio[1265]: info: MPD running with PID1559 May 25 14:12:36 smartradio volumio[1265]: ,establishing connection May 25 14:12:36 smartradio volumio[1265]: error: updateQueue error: null May 25 14:12:37 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 25 14:12:37 smartradio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 25 14:12:37 smartradio volumio[1265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 25 14:12:37 smartradio volumio[1265]: SPOTIFY: BQAx8HdiVnQhLfqThCfPrzEoaaJva1LtHuKnsFOOeVmnhnAuuHgUrpHC2-daJeq_YBBStRrIypBL_NTCC72Z6oLKZK8dnc2UW16D9_KKY4xB0AtCq0FZzIGii3CXOESiy4-T9xvQlfoRc1jsSNAM7Rr5F2Yai_4oAWtwK2Tf3SSY9KfpDJdZPLiWnTGE_wqkQGmkQXEpwC9-el-3TenIDA3QJlGe4W6TmAMT4FtEvPmt1BHa_Beyip_MSYJ1G2uAgxEapQNprG-UDSNjrZb0eAfUQ0IiHzOttTpwFCiWbOmmu8DK2IYhsVMZ5g May 25 14:12:37 smartradio volumio[1265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 25 14:12:37 smartradio volumio[1265]: info: New Spotify access token = BQAx8HdiVnQhLfqThCfPrzEoaaJva1LtHuKnsFOOeVmnhnAuuHgUrpHC2-daJeq_YBBStRrIypBL_NTCC72Z6oLKZK8dnc2UW16D9_KKY4xB0AtCq0FZzIGii3CXOESiy4-T9xvQlfoRc1jsSNAM7Rr5F2Yai_4oAWtwK2Tf3SSY9KfpDJdZPLiWnTGE_wqkQGmkQXEpwC9-el-3TenIDA3QJlGe4W6TmAMT4FtEvPmt1BHa_Beyip_MSYJ1G2uAgxEapQNprG-UDSNjrZb0eAfUQ0IiHzOttTpwFCiWbOmmu8DK2IYhsVMZ5g May 25 14:12:37 smartradio volumio[1265]: info: Spotify credentials grant success - running version from March 24, 2019 May 25 14:12:37 smartradio volumio[1265]: info: Starting Shairport Sync May 25 14:12:37 smartradio volumio[1265]: info: Starting Shairport Sync May 25 14:12:37 smartradio volumio[1265]: info: Starting Shairport Sync May 25 14:12:38 smartradio volumio[1265]: error: updateQueue error: null May 25 14:12:38 smartradio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 14:12:38 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47:3000 from 192.168.2.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 May 25 14:12:38 smartradio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:38 smartradio sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 14:12:38 smartradio sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:38 smartradio sudo[1692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 14:12:38 smartradio sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:38 smartradio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 14:12:38 smartradio systemd[1]: shairport-sync.service: Deactivated successfully. May 25 14:12:38 smartradio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 14:12:38 smartradio systemd[1]: shairport-sync.service: Consumed 2.522s CPU time. May 25 14:12:38 smartradio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 14:12:38 smartradio sudo[1692]: pam_unix(sudo:session): session closed for user root May 25 14:12:38 smartradio sudo[1688]: pam_unix(sudo:session): session closed for user root May 25 14:12:38 smartradio sudo[1690]: pam_unix(sudo:session): session closed for user root May 25 14:12:38 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47:3000 from 192.168.2.29 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 May 25 14:12:39 smartradio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 16 May 25 14:12:39 smartradio volumio[1265]: info: Shairport-Sync Started May 25 14:12:39 smartradio volumio[1265]: Error adding Membership: Error: addMembership EINVAL May 25 14:12:39 smartradio volumio[1265]: info: Shairport-Sync Started May 25 14:12:39 smartradio volumio[1265]: info: Shairport-Sync Started May 25 14:12:39 smartradio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 16 May 25 14:12:39 smartradio volumio[1265]: info: Received Get System Info May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 14:12:39 smartradio volumio[1265]: info: Discovery: Getting this device information May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::volumioGetState May 25 14:12:39 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 14:12:39 smartradio volumio5-onboarding[1626]: time=2026-05-25T14:12:39.366+02:00 level=INFO msg="system info for eff628749a68a6668131abadf7e4ceb3" deviceName=smartradio deviceVariant=volumio deviceModel= softwareVersion=4.103 May 25 14:12:39 smartradio volumio5-onboarding[1626]: time=2026-05-25T14:12:39.387+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 25 14:12:39 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 May 25 14:12:39 smartradio volumio[1265]: info: Received Get System Info May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 14:12:39 smartradio volumio[1265]: info: Discovery: Getting this device information May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::volumioGetState May 25 14:12:39 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:39 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 14:12:39 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 May 25 14:12:39 smartradio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 May 25 14:12:39 smartradio volumio[1265]: info: go-librespot daemon successfully initialized May 25 14:12:39 smartradio volumio[1265]: error: [ROTARYENCODER2] dtoverlayAdd failed: Device /dev/input/by-path/platform-rotary@2-event did not appear after waiting for 5000ms. May 25 14:12:39 smartradio volumio[1265]: error: [ROTARYENCODER2] installAllOverlays failed for rotary: {"pinA":2,"type":"rotary-encoder"} - Device /dev/input/by-path/platform-rotary@2-event did not appear after waiting for 5000ms. May 25 14:12:39 smartradio volumio[1265]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 25 14:12:39 smartradio volumio[1265]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_ADD_OVERLAY_FAILED"):Hinzufügen gescheitert für Overlay: May 25 14:12:40 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayAdd May 25 14:12:40 smartradio volumio[1265]: info: [ROTARYENCODER2] {"rotaryIdx":0,"type":"gpio-key","pinA":25,"debounce":10,"action":2,"socketCmdPush":"","socketDataPush":"","longPushAction":1,"socketCmdLongPush":"","socketDataLongPush":"","delayLongPush":1500,"doublePushAction":4,"socketCmdDoublePush":"","socketDataDoublePush":"","delayDoublePush":700,"activeLow":true,"gpioPull":"up","keycode":20} May 25 14:12:40 smartradio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 May 25 14:12:40 smartradio sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=25 active_low=true gpio_pull=up keycode=20 May 25 14:12:40 smartradio sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:40 smartradio kernel: input: button@19 as /devices/platform/button@19/input/input2 May 25 14:12:40 smartradio sudo[1714]: pam_unix(sudo:session): session closed for user root May 25 14:12:40 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayAdd executed: /usr/bin/sudo /usr/bin/dtoverlay gpio-key gpio=25 active_low=true gpio_pull=up keycode=20 May 25 14:12:41 smartradio volumio[1265]: SPOTIFY: User informations: {"country":"DE","display_name":"Mo","email":"moritz.merkel@mercom-it.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/488bnpx2x1zch0yt2w78zyszl"},"followers":{"href":null,"total":16},"href":"https://api.spotify.com/v1/users/488bnpx2x1zch0yt2w78zyszl","id":"488bnpx2x1zch0yt2w78zyszl","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee8594f12c52ab3f0f5d653f0349","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b8294f12c52ab3f0f5d653f0349","width":64}],"product":"premium","type":"user","uri":"spotify:user:488bnpx2x1zch0yt2w78zyszl"} May 25 14:12:41 smartradio volumio[1265]: info: Spotify Successfully logged in May 25 14:12:41 smartradio volumio[1265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 14:12:41 smartradio volumio[1265]: info: [1779711161255] CoreMusicLibrary::Adding element Spotify May 25 14:12:41 smartradio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 14:12:41 smartradio volumio[1265]: Cannot find translation for source Spotify May 25 14:12:42 smartradio volumio[1265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 14:12:44 smartradio volumio-remote-updater[681]: [2026-05-25 14:12:44] [connect] Successful connection May 25 14:12:47 smartradio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.87 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 May 25 14:12:47 smartradio volumio[1265]: error: [ROTARYENCODER2] dtoverlayAdd failed: Device /dev/input/by-path/platform-button@19-event did not appear after waiting for 5000ms. May 25 14:12:47 smartradio volumio[1265]: error: [ROTARYENCODER2] installAllOverlays failed for rotary: {"pinA":25,"type":"gpio-key"} - Device /dev/input/by-path/platform-button@19-event did not appear after waiting for 5000ms. May 25 14:12:47 smartradio volumio[1265]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 25 14:12:47 smartradio volumio[1265]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_ADD_OVERLAY_FAILED"):Hinzufügen gescheitert für Overlay: May 25 14:12:47 smartradio volumio[1265]: info: [ROTARYENCODER2] dtoverlayAdd May 25 14:12:47 smartradio volumio[1265]: info: [ROTARYENCODER2] {"rotaryIdx":1,"type":"rotary-encoder","pinA":5,"pinB":6,"dialAction":1,"socketCmdCW":"","socketDataCW":"","socketCmdCCW":"","socketDataCCW":"","relativeAxis":true,"stepsPerPeriod":2} May 25 14:12:47 smartradio volumio[1265]: info: Initializing connection to go-librespot Websocket May 25 14:12:47 smartradio volumio[1265]: info: AutoStart - Plugin is starting May 25 14:12:47 smartradio volumio[1265]: info: CoreCommandRouter::volumioGetQueue May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::getQueue May 25 14:12:47 smartradio volumio[1265]: info: CorePlayQueue::getQueue May 25 14:12:47 smartradio sudo[1739]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 25 14:12:47 smartradio sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 14:12:47 smartradio volumio[1265]: info: AutoStart - start playing May 25 14:12:47 smartradio volumio[1265]: info: AutoStart - start playing with no specific position May 25 14:12:47 smartradio volumio[1265]: info: CoreCommandRouter::volumioPlay May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::play index 0 May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::stop May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::play index undefined May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 14:12:47 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:47 smartradio volumio[1265]: info: CoreStateMachine::startPlaybackTimer May 25 14:12:47 smartradio volumio[1265]: info: CorePlayQueue::getTrack 0 May 25 14:12:47 smartradio volumio[1265]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/3 Doors Down/Seventeen Days/01 Right Where I Belong.mp3 May 25 14:12:47 smartradio kernel: rotary-encoder rotary@5: gray May 25 14:12:47 smartradio volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop May 25 14:12:47 smartradio volumio[1265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 14:12:47 smartradio kernel: input: rotary@5 as /devices/platform/rotary@5/input/input3 May 25 14:12:47 smartradio sudo[1739]: pam_unix(sudo:session): session closed for user root May 25 14:12:47 smartradio volumio[1265]: Error: Unable to resolve or reject the same promise twice May 25 14:12:47 smartradio volumio[1265]: at Promise.resolve (/data/plugins/system_hardware/rotaryencoder2/node_modules/kew/kew.js:140:43) May 25 14:12:47 smartradio volumio[1265]: at /data/plugins/system_hardware/rotaryencoder2/index.js:999:23 May 25 14:12:47 smartradio volumio[1265]: at FSReqCallback.oncomplete (node:fs:190:23) May 25 14:12:47 smartradio volumio[1265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 14:12:57 smartradio sudo[1777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 14:11' May 25 14:12:57 smartradio sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"