Jan 25 09:54:13 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 25 09:54:13 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 25 09:54:13 volumio systemd[1]: setdatetime-helper.service: Consumed 2.917s CPU time. Jan 25 09:54:13 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jan 25 09:54:13 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jan 25 09:54:13 volumio nmbd[949]: [2026/01/25 09:54:13.241776, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 25 09:54:13 volumio nmbd[949]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.88 for name WORKGROUP<1d>. Jan 25 09:54:13 volumio nmbd[949]: This response was from IP 192.168.0.109, reporting an IP address of 192.168.0.109. Jan 25 09:54:13 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jan 25 09:54:13 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jan 25 09:54:13 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jan 25 09:54:14 volumio smbd[1008]: [2026/01/25 09:54:14.195998, 0] ../../source3/smbd/server.c:1741(main) Jan 25 09:54:14 volumio smbd[1008]: smbd version 4.17.8-Raspbian started. Jan 25 09:54:14 volumio smbd[1008]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 25 09:54:14 volumio winbindd[977]: [2026/01/25 09:54:14.417499, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Jan 25 09:54:14 volumio winbindd[977]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'VOLUMIO' failed 'NT_STATUS_IO_TIMEOUT' Jan 25 09:54:14 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 25 09:54:16 volumio wireless.js[644]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Jan 25 09:54:16 volumio wireless.js[644]: WIRELESS.JS: Wired network status changed to: ---connected--- Jan 25 09:54:16 volumio wireless.js[644]: WIRELESS.JS: Wireless.js initializing wireless flow Jan 25 09:54:16 volumio wireless.js[644]: WIRELESS.JS: Cleaning previous... Jan 25 09:54:16 volumio wireless.js[644]: WIRELESS.JS: No wireless interface, exiting Jan 25 09:54:16 volumio systemd[1]: wireless.service: Killing process 1031 (sh) with signal SIGKILL. Jan 25 09:54:16 volumio systemd[1]: wireless.service: Killing process 1032 (systemctl) with signal SIGKILL. Jan 25 09:54:16 volumio systemd[1]: wireless.service: Failed with result 'protocol'. Jan 25 09:54:16 volumio systemd[1]: Failed to start wireless.service - Wireless Services. Jan 25 09:54:16 volumio systemd[1]: wireless.service: Consumed 13.727s CPU time. Jan 25 09:54:16 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 25 09:54:16 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jan 25 09:54:16 volumio systemd[1]: Starting pydpiper-kick.service - Kick pydPiper after boot (2x restart)... Jan 25 09:54:16 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jan 25 09:54:16 volumio systemd[1]: Received SIGRTMIN+21 from PID 274 (plymouthd). Jan 25 09:54:16 volumio systemd[1]: Received SIGRTMIN+21 from PID 274 (plymouthd). Jan 25 09:54:16 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jan 25 09:54:16 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jan 25 09:54:16 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 25 09:54:16 volumio systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jan 25 09:54:17 volumio systemd[1]: Reached target getty.target - Login Prompts. Jan 25 09:54:17 volumio volumio-cpu-tweak[1037]: Setting RT Priority for mpd Jan 25 09:54:17 volumio volumio-cpu-tweak[1054]: pid 35's current scheduling policy: SCHED_OTHER Jan 25 09:54:17 volumio volumio-cpu-tweak[1054]: pid 35's current scheduling priority: 0 Jan 25 09:54:17 volumio volumio-cpu-tweak[1037]: Setting MPD Affinity Jan 25 09:54:17 volumio volumio-cpu-tweak[1055]: pid 3's current affinity mask: f Jan 25 09:54:17 volumio volumio-cpu-tweak[1037]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 25 09:54:17 volumio volumio-cpu-tweak[1037]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 25 09:54:17 volumio volumio-remote-updater[639]: [2026-01-25 09:54:17] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 25 09:54:17 volumio volumio-remote-updater[639]: [2026-01-25 09:54:17] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 25 09:54:17 volumio volumio-remote-updater[639]: [2026-01-25 09:54:17] [error] handle_connect error: Connection refused Jan 25 09:54:17 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jan 25 09:54:17 volumio winbindd[988]: [2026/01/25 09:54:17.327972, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Jan 25 09:54:17 volumio winbindd[988]: Could not write result Jan 25 09:54:17 volumio python[779]: pydPiper starting Jan 25 09:54:17 volumio python[779]: Loading display controller Jan 25 09:54:17 volumio python[779]: Loading music controller Jan 25 09:54:17 volumio python[779]: Starting new HTTP connection (1): localhost:3000 Jan 25 09:54:17 volumio python[779]: Exception in thread Thread-2 (run): Jan 25 09:54:17 volumio python[779]: Traceback (most recent call last): Jan 25 09:54:17 volumio python[779]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 276, in connect Jan 25 09:54:17 volumio python[779]: self.eio.connect(url, headers=headers, transports=transports, Jan 25 09:54:17 volumio python[779]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/engineio/client.py", line 194, in connect Jan 25 09:54:17 volumio python[779]: return getattr(self, '_connect_' + self.transports[0])( Jan 25 09:54:17 volumio python[779]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Jan 25 09:54:17 volumio python[779]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/engineio/client.py", line 300, in _connect_polling Jan 25 09:54:17 volumio python[779]: raise exceptions.ConnectionError( Jan 25 09:54:17 volumio python[779]: engineio.exceptions.ConnectionError: Connection refused by the server Jan 25 09:54:17 volumio python[779]: During handling of the above exception, another exception occurred: Jan 25 09:54:17 volumio python[779]: Traceback (most recent call last): Jan 25 09:54:17 volumio python[779]: File "/usr/lib/python3.11/threading.py", line 1038, in _bootstrap_inner Jan 25 09:54:17 volumio python[779]: self.run() Jan 25 09:54:17 volumio python[779]: File "/usr/lib/python3.11/threading.py", line 975, in run Jan 25 09:54:17 volumio python[779]: self._target(*self._args, **self._kwargs) Jan 25 09:54:17 volumio python[779]: File "/home/volumio/pydPiper_test/pydPiper/app/sources/musicdata_volumio.py", line 193, in run Jan 25 09:54:17 volumio python[779]: self.sio.connect(f"http://{self.server}:{self.port}") Jan 25 09:54:17 volumio python[779]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 279, in connect Jan 25 09:54:17 volumio python[779]: self._trigger_event( Jan 25 09:54:17 volumio python[779]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 550, in _trigger_event Jan 25 09:54:17 volumio python[779]: return self.handlers[namespace][event](*args) Jan 25 09:54:17 volumio python[779]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Jan 25 09:54:17 volumio python[779]: TypeError: musicdata_volumio3.init_callbacks..connect_error() takes 0 positional arguments but 1 was given Jan 25 09:54:19 volumio python[779]: Music Controller Starting Jan 25 09:54:19 volumio python[779]: Loading app/pages/pages_lcd_16x2.py as page file Jan 25 09:54:19 volumio python[779]: Initializing current system status update process. System status will update every five minutes Jan 25 09:54:19 volumio python[779]: Weather service not enabled Jan 25 09:54:19 volumio python[779]: Weather service not enabled Jan 25 09:54:20 volumio python[779]: System status: Temp 26°c, Disk space remaining 92%, IP address uid Jan 25 09:54:20 volumio python[779]: Loading image progbar Jan 25 09:54:20 volumio python[779]: v out of range with value -1. Should have been between 0 and 100 Jan 25 09:54:20 volumio python[779]: Trying to add widget playartist to sequence seqPlay but widget was not found Jan 25 09:54:20 volumio python[779]: Trying to add widget playalbum to sequence seqPlay but widget was not found Jan 25 09:54:20 volumio python[779]: Trying to add widget playartist_radio to sequence seqPlay but widget was not found Jan 25 09:54:20 volumio python[779]: Trying to add widget playalbum_radio to sequence seqPlay but widget was not found Jan 25 09:54:20 volumio python[779]: Trying to add widget playtitle_radio to sequence seqPlay but widget was not found Jan 25 09:54:20 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Jan 25 09:54:20 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Jan 25 09:54:20 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 09:54:20 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 09:54:20 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Jan 25 09:54:20 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Jan 25 09:54:20 volumio upmpdcli[1075]: Could not open config: /tmp/upmpdcli.conf Jan 25 09:54:20 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 25 09:54:20 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 25 09:54:22 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:54:22 volumio volumio[1034]: info: ----- Volumio3 ---- Jan 25 09:54:22 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:54:22 volumio volumio[1034]: info: ----- System startup ---- Jan 25 09:54:22 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:54:25 volumio volumio[1034]: info: MYVOLUMIO Environment detected Jan 25 09:54:25 volumio volumio[1034]: info: Plugin folders cleanup Jan 25 09:54:25 volumio volumio[1034]: info: Scanning into folder /volumio/app/plugins/ Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category audio_interface Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category miscellanea Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category music_service Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category plugins.json Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category system_controller Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category user_interface Jan 25 09:54:25 volumio volumio[1034]: info: Scanning into folder /data/plugins/ Jan 25 09:54:25 volumio volumio[1034]: info: Scanning category system_hardware Jan 25 09:54:25 volumio volumio[1034]: info: Plugin folders cleanup completed Jan 25 09:54:25 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:54:25 volumio volumio[1034]: info: ----- Core plugins startup ---- Jan 25 09:54:25 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:54:25 volumio volumio[1034]: info: Loading plugins from folder /volumio/app/plugins/ Jan 25 09:54:25 volumio volumio[1034]: info: Adding plugin upnp to MyMusic Plugins Jan 25 09:54:25 volumio volumio[1034]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 25 09:54:25 volumio volumio[1034]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 25 09:54:25 volumio volumio[1034]: info: Loading plugins from folder /data/plugins/ Jan 25 09:54:25 volumio volumio[1034]: info: Loading plugin "system"... Jan 25 09:54:25 volumio volumio[1034]: info: Loading plugin "appearance"... Jan 25 09:54:27 volumio volumio-remote-updater[639]: [2026-01-25 09:54:27] [connect] Successful connection Jan 25 09:54:29 volumio volumio[1034]: info: Loading plugin "network"... Jan 25 09:54:29 volumio volumio[1034]: info: Refreshing Cached IP Addresses Jan 25 09:54:29 volumio volumio[1034]: info: Loading plugin "services"... Jan 25 09:54:29 volumio volumio[1034]: info: Loading plugin "alsa_controller"... Jan 25 09:54:29 volumio sudo[1091]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 09:54:29 volumio sudo[1098]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 25 09:54:29 volumio sudo[1091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:29 volumio sudo[1089]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 09:54:29 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:29 volumio sudo[1089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:29 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:29 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:29 volumio sudo[1091]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:29 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 09:54:29 volumio volumio[1034]: info: Loading plugin "wizard"... Jan 25 09:54:29 volumio volumio[1034]: info: Loading plugin "networkfs"... Jan 25 09:54:29 volumio volumio[1034]: info: Starting Udev Watcher for removable devices Jan 25 09:54:29 volumio sudo[1127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.0.109/music\\muza /mnt/NAS/music Jan 25 09:54:29 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:29 volumio volumio[1034]: info: Ignoring mount for partition: boot Jan 25 09:54:29 volumio volumio[1034]: info: Ignoring mount for partition: volumio Jan 25 09:54:29 volumio volumio[1034]: info: Ignoring mount for partition: volumio_data Jan 25 09:54:30 volumio volumio[1034]: info: Ignoring mount for partition: INTERNAL Jan 25 09:54:30 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 09:54:30 volumio volumio[1034]: info: Loading plugin "volumio_command_line_client"... Jan 25 09:54:30 volumio kernel: netfs: FS-Cache loaded Jan 25 09:54:30 volumio volumio[1034]: info: Loading plugin "upnp"... Jan 25 09:54:30 volumio volumio[1034]: info: [1769331270159] Starting Upmpd Daemon Jan 25 09:54:30 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 09:54:30 volumio volumio[1034]: info: Loading plugin "my_music"... Jan 25 09:54:30 volumio volumio[1034]: info: Loading plugin "mpd"... Jan 25 09:54:30 volumio kernel: Key type cifs.spnego registered Jan 25 09:54:30 volumio kernel: Key type cifs.idmap registered Jan 25 09:54:30 volumio kernel: CIFS: Attempting to mount //192.168.0.109/music/muza Jan 25 09:54:30 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 25 09:54:31 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:31 volumio volumio[1034]: info: Loading plugin "upnp_browser"... Jan 25 09:54:33 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 25 09:54:33 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 25 09:54:33 volumio systemd[1]: setdatetime-helper.service: Consumed 2.158s CPU time. Jan 25 09:54:35 volumio volumio[1034]: info: Starting UPNP Browser Jan 25 09:54:35 volumio volumio[1034]: info: Loading plugin "alarm-clock"... Jan 25 09:54:35 volumio volumio[1034]: info: Loading plugin "airplay_emulation"... Jan 25 09:54:35 volumio volumio[1034]: info: Starting Shairport Sync Jan 25 09:54:35 volumio volumio[1034]: info: Loading plugin "last_100"... Jan 25 09:54:35 volumio volumio[1034]: info: Loading plugin "webradio"... Jan 25 09:54:35 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 25 09:54:35 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 09:54:36 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 09:54:36 volumio volumio[1034]: info: Loading plugin "i2s_dacs"... Jan 25 09:54:36 volumio upmpdcli[1191]: Could not open config: /tmp/upmpdcli.conf Jan 25 09:54:36 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 25 09:54:36 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 25 09:54:36 volumio volumio[1034]: info: I2S DAC not set, start Auto-detection Jan 25 09:54:36 volumio volumio[1034]: info: Loading plugin "volumiodiscovery"... Jan 25 09:54:36 volumio volumio[1034]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 25 09:54:36 volumio volumio[1034]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 09:54:36 volumio volumio[1034]: *** WARNING *** For more information see Jan 25 09:54:36 volumio volumio[1034]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 25 09:54:36 volumio volumio[1034]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 09:54:36 volumio volumio[1034]: *** WARNING *** For more information see Jan 25 09:54:36 volumio node[1034]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 25 09:54:36 volumio node[1034]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 09:54:36 volumio node[1034]: *** WARNING *** For more information see Jan 25 09:54:36 volumio node[1034]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 25 09:54:36 volumio node[1034]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 25 09:54:36 volumio node[1034]: *** WARNING *** For more information see Jan 25 09:54:36 volumio volumio[1034]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 25 09:54:36 volumio volumio[1034]: info: Discovery: Started advertising with name: Volumio Jan 25 09:54:36 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 09:54:36 volumio volumio[1034]: info: Loading plugin "gpio-buttons"... Jan 25 09:54:36 volumio volumio[1034]: gpiox_arm32 V2.2.1 Jan 25 09:54:37 volumio volumio[1034]: info: gpio-buttons - GPIO-Buttons initialized Jan 25 09:54:37 volumio volumio[1034]: info: Loading plugin "outputs"... Jan 25 09:54:37 volumio volumio[1034]: info: Loading plugin "albumart"... Jan 25 09:54:37 volumio volumio[1034]: info: Plugin example_plugin is not enabled Jan 25 09:54:37 volumio volumio[1034]: info: Loading plugin "inputs"... Jan 25 09:54:37 volumio volumio[1034]: info: Loading plugin "updater_comm"... Jan 25 09:54:38 volumio volumio[1034]: info: Plugin mpdemulation is not enabled Jan 25 09:54:38 volumio volumio[1034]: info: Loading plugin "rest_api"... Jan 25 09:54:38 volumio volumio[1034]: info: Loading plugin "websocket"... Jan 25 09:54:38 volumio volumio[1034]: info: Starting Socket.io Server version 1.7.4 Jan 25 09:54:38 volumio volumio[1034]: info: Loading plugin "gpio_control"... Jan 25 09:54:38 volumio volumio[1034]: gpiox_arm32 V2.2.1 Jan 25 09:54:39 volumio volumio[1194]: Forking 3 albumart workers Jan 25 09:54:41 volumio volumio[1034]: info: Applying required configuration parameters for plugin gpio_control Jan 25 09:54:41 volumio volumio[1034]: info: Loading plugin "rotaryencoder2"... Jan 25 09:54:41 volumio systemd[1]: Stopping pydpiper.service - LCD server for Volumio (pydPiper)... Jan 25 09:54:42 volumio volumio-remote-updater[639]: [2026-01-25 09:54:42] [connect] Successful connection Jan 25 09:54:45 volumio python[779]: Exiting... Jan 25 09:54:45 volumio python[779]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_fixed.fnt Jan 25 09:54:45 volumio python[779]: Connected to Volumio Web Service Jan 25 09:54:45 volumio python[779]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_lcd.fnt Jan 25 09:54:45 volumio python[779]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/BigFont_10x16_fixed.fnt Jan 25 09:54:45 volumio python[779]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/upperasciiwide_3x5_fixed.fnt Jan 25 09:54:45 volumio python[779]: Shutting down threads Jan 25 09:54:45 volumio volumio[1034]: info: Loading i18n strings for locale pl Jan 25 09:54:45 volumio systemd[1]: pydpiper.service: Deactivated successfully. Jan 25 09:54:45 volumio systemd[1]: Stopped pydpiper.service - LCD server for Volumio (pydPiper). Jan 25 09:54:45 volumio systemd[1]: pydpiper.service: Consumed 12.364s CPU time. Jan 25 09:54:45 volumio volumio[1034]: Updating browse sources language Jan 25 09:54:45 volumio systemd[1]: Started pydpiper.service - LCD server for Volumio (pydPiper). Jan 25 09:54:45 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 09:54:46 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::initPlayerControls Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 09:54:48 volumio volumio[1034]: Express server listening on port 3000 Jan 25 09:54:48 volumio volumio[1034]: [Metrics] WebUI: 27s 112.20ms Jan 25 09:54:48 volumio volumio[1034]: info: CoreStateMachine::resetVolumioState Jan 25 09:54:48 volumio volumio[1034]: info: CoreStateMachine::getcurrentVolume Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 09:54:48 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:54:49 volumio python[1249]: pydPiper starting Jan 25 09:54:49 volumio sudo[1256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 09:54:49 volumio sudo[1256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:49 volumio python[1249]: Loading display controller Jan 25 09:54:49 volumio python[1249]: Loading music controller Jan 25 09:54:49 volumio sudo[1258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 09:54:49 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:49 volumio sudo[1256]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:49 volumio python[1249]: Starting new HTTP connection (1): localhost:3000 Jan 25 09:54:49 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:49 volumio volumio[1034]: info: Volumio Network Manager: Network status updated: 1 Jan 25 09:54:49 volumio volumio[1034]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 25 09:54:49 volumio volumio[1034]: wlan0 Interface doesn't support scanning. Jan 25 09:54:49 volumio volumio[1034]: info: Cannot use regular scanning, forcing with ap-force Jan 25 09:54:50 volumio sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 25 09:54:50 volumio sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:50 volumio sudo[1281]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:50 volumio volumio[1034]: command failed: No such device (-19) Jan 25 09:54:50 volumio volumio[1034]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 25 09:54:50 volumio volumio[1034]: command failed: No such device (-19) Jan 25 09:54:51 volumio volumio[1034]: info: VolumeController:: Volume=100 Mute =false Jan 25 09:54:51 volumio volumio[1034]: info: CoreStateMachine::pushState Jan 25 09:54:51 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:54:51 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 09:54:51 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jan 25 09:54:51 volumio volumio[1034]: info: CoreStateMachine::updateTrackBlock Jan 25 09:54:51 volumio volumio[1034]: info: CorePlayQueue::getTrackBlock Jan 25 09:54:51 volumio volumio[1034]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 09:54:51 volumio python[1249]: Music Controller Starting Jan 25 09:54:51 volumio python[1249]: Loading app/pages/pages_lcd_16x2.py as page file Jan 25 09:54:51 volumio python[1249]: Initializing current system status update process. System status will update every five minutes Jan 25 09:54:51 volumio python[1249]: Weather service not enabled Jan 25 09:54:51 volumio python[1249]: Weather service not enabled Jan 25 09:54:51 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 25 09:54:51 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 09:54:51 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 09:54:51 volumio python[1249]: System status: Temp 28°c, Disk space remaining 92%, IP address uid Jan 25 09:54:51 volumio upmpdcli[1298]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.0.88 port 49152 IPV6 port 49152 Jan 25 09:54:51 volumio upmpdcli[1298]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Jan 25 09:54:51 volumio upmpdcli[1298]: :2:../src/main.cxx:728::MPD connection failed Jan 25 09:54:51 volumio python[1249]: Loading image progbar Jan 25 09:54:51 volumio python[1249]: v out of range with value -1. Should have been between 0 and 100 Jan 25 09:54:51 volumio python[1249]: Trying to add widget playartist to sequence seqPlay but widget was not found Jan 25 09:54:51 volumio python[1249]: Trying to add widget playalbum to sequence seqPlay but widget was not found Jan 25 09:54:51 volumio python[1249]: Trying to add widget playartist_radio to sequence seqPlay but widget was not found Jan 25 09:54:51 volumio python[1249]: Trying to add widget playalbum_radio to sequence seqPlay but widget was not found Jan 25 09:54:51 volumio python[1249]: Trying to add widget playtitle_radio to sequence seqPlay but widget was not found Jan 25 09:54:51 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.0.88:3000 from 192.168.0.125 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 25 09:54:52 volumio volumio[1034]: info: Reloading queue from file Jan 25 09:54:52 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.0.88:3000 from 192.168.0.125 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 25 09:54:52 volumio volumio[1034]: info: CoreStateMachine::setRepeat false single undefined Jan 25 09:54:52 volumio volumio[1034]: info: CoreStateMachine::pushState Jan 25 09:54:52 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:54:52 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 09:54:52 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jan 25 09:54:52 volumio volumio[1034]: info: CoreStateMachine::setRandom null Jan 25 09:54:52 volumio volumio[1034]: info: CoreStateMachine::pushState Jan 25 09:54:52 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:54:52 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jan 25 09:54:52 volumio volumio[1218]: Starting albumart workers Jan 25 09:54:52 volumio volumio[1034]: info: Setting Device type: Raspberry PI Jan 25 09:54:53 volumio volumio[1220]: Starting albumart workers Jan 25 09:54:53 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.0.88:3000 from 192.168.0.125 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 25 09:54:53 volumio volumio[1034]: info: VolumeController:: Volume=100 Mute =false Jan 25 09:54:53 volumio volumio[1034]: info: CoreStateMachine::pushState Jan 25 09:54:53 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:54:53 volumio volumio[1034]: info: CoreCommandRouter::volumioPushState Jan 25 09:54:53 volumio volumio[1219]: Starting albumart workers Jan 25 09:54:53 volumio upmpdcli[1298]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Jan 25 09:54:53 volumio upmpdcli[1298]: :2:../src/main.cxx:728::MPD connection failed Jan 25 09:54:53 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 25 09:54:53 volumio volumio[1034]: info: Discovery: adding b1384ca2-a9a4-4b11-af6f-04cd5c3872a6 Jan 25 09:54:53 volumio volumio[1034]: info: Discovery: Found device Volumio Jan 25 09:54:53 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:54:53 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:54:53 volumio volumio[1034]: info: Discovery: this is already registered, b1384ca2-a9a4-4b11-af6f-04cd5c3872a6 Jan 25 09:54:53 volumio volumio[1034]: info: Discovery: Found device Volumio Jan 25 09:54:53 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:54:53 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:54:53 volumio sudo[1322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 25 09:54:53 volumio sudo[1322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:53 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 25 09:54:54 volumio volumio[1034]: info: Discovery: adding 7bd79a2c-e52f-458f-b231-4b6b7fdfe306 Jan 25 09:54:54 volumio volumio[1034]: info: Discovery: Found device Volumio4 Jan 25 09:54:54 volumio sudo[1322]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:54 volumio volumio[1034]: info: Discovery: Connecting to remote: 192.168.0.125 Jan 25 09:54:54 volumio python[1249]: Exception in thread Thread-2 (run): Jan 25 09:54:54 volumio python[1249]: Traceback (most recent call last): Jan 25 09:54:54 volumio python[1249]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 276, in connect Jan 25 09:54:54 volumio python[1249]: self.eio.connect(url, headers=headers, transports=transports, Jan 25 09:54:54 volumio python[1249]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/engineio/client.py", line 194, in connect Jan 25 09:54:54 volumio python[1249]: return getattr(self, '_connect_' + self.transports[0])( Jan 25 09:54:54 volumio python[1249]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Jan 25 09:54:54 volumio python[1249]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/engineio/client.py", line 300, in _connect_polling Jan 25 09:54:54 volumio python[1249]: raise exceptions.ConnectionError( Jan 25 09:54:54 volumio python[1249]: engineio.exceptions.ConnectionError: Connection refused by the server Jan 25 09:54:54 volumio python[1249]: During handling of the above exception, another exception occurred: Jan 25 09:54:54 volumio python[1249]: Traceback (most recent call last): Jan 25 09:54:54 volumio python[1249]: File "/usr/lib/python3.11/threading.py", line 1038, in _bootstrap_inner Jan 25 09:54:54 volumio python[1249]: self.run() Jan 25 09:54:54 volumio python[1249]: File "/usr/lib/python3.11/threading.py", line 975, in run Jan 25 09:54:54 volumio python[1249]: self._target(*self._args, **self._kwargs) Jan 25 09:54:54 volumio python[1249]: File "/home/volumio/pydPiper_test/pydPiper/app/sources/musicdata_volumio.py", line 193, in run Jan 25 09:54:54 volumio python[1249]: self.sio.connect(f"http://{self.server}:{self.port}") Jan 25 09:54:54 volumio python[1249]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 279, in connect Jan 25 09:54:54 volumio python[1249]: self._trigger_event( Jan 25 09:54:54 volumio python[1249]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 550, in _trigger_event Jan 25 09:54:54 volumio python[1249]: return self.handlers[namespace][event](*args) Jan 25 09:54:54 volumio python[1249]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Jan 25 09:54:54 volumio python[1249]: TypeError: musicdata_volumio3.init_callbacks..connect_error() takes 0 positional arguments but 1 was given Jan 25 09:54:54 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.32.5 Engine version: 3 Transport: polling Total Clients: 6 Jan 25 09:54:54 volumio volumio[1034]: verbose: New Socket.io Connection to 192.168.0.88:3000 from 192.168.0.125 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 25 09:54:54 volumio volumio[1034]: info: Upmpdcli Daemon Started Jan 25 09:54:54 volumio volumio[1034]: info: Completed loading Core Plugins Jan 25 09:54:54 volumio volumio[1034]: info: Preparing to generate the ALSA configuration file Jan 25 09:54:55 volumio volumio[1034]: info: Discovery: Connected to remote: 192.168.0.125 Jan 25 09:54:55 volumio volumio[1034]: info: Asound.conf file unchanged, so no further update is needed Jan 25 09:54:55 volumio volumio[1034]: info: Output device has changed, restarting MPD Jan 25 09:54:55 volumio volumio[1034]: info: Output device has changed, restarting Shairport Sync Jan 25 09:54:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:54:55 volumio sudo[1327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 09:54:55 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:55 volumio sudo[1325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 09:54:55 volumio sudo[1325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:55 volumio sudo[1325]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:55 volumio volumio[1034]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 09:54:55 volumio volumio[1034]: info: ___________ START PLUGINS ___________ Jan 25 09:54:55 volumio volumio[1034]: info: ControllerMpd::onStart: Initializing MPD Jan 25 09:54:55 volumio volumio[1034]: info: Creating MPD Configuration file Jan 25 09:54:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 09:54:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 09:54:55 volumio volumio[1034]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 09:54:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 09:54:55 volumio volumio[1034]: info: [1769331295776] CoreMusicLibrary::Adding element Serwery Mediów Jan 25 09:54:55 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 09:54:55 volumio sudo[1335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 09:54:55 volumio sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:56 volumio volumio[1034]: info: UPNP Browser: Client initialized successfully Jan 25 09:54:56 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:56 volumio sudo[1338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 09:54:56 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:54:56 volumio sudo[1339]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 09:54:56 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 09:54:56 volumio sudo[1343]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 25 09:54:56 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:56 volumio volumio[1034]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:54:56 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 25 09:54:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 09:54:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 25 09:54:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 09:54:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 09:54:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 09:54:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 09:54:56 volumio volumio[1034]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 09:54:56 volumio volumio[1034]: info: [1769331296929] CoreMusicLibrary::Adding element Last_100 Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 09:54:56 volumio volumio[1034]: info: [1769331296995] CoreMusicLibrary::Adding element Webradio Jan 25 09:54:56 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 09:54:57 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 09:54:57 volumio volumio[1034]: info: Initializing BBC Radios Jan 25 09:54:57 volumio volumio-remote-updater[639]: [2026-01-25 09:54:57] [connect] Successful connection Jan 25 09:54:57 volumio sudo[1353]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 09:54:57 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 09:54:57 volumio sudo[1356]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 25 09:54:57 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:57 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 09:54:57 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:54:57 volumio volumio[1034]: info: gpio-buttons - Initializing GPIO triggers... Jan 25 09:54:57 volumio volumio[1034]: info: gpio-buttons - ✅ GPIO24 configured for playPause Jan 25 09:54:57 volumio volumio[1034]: info: gpio-buttons - ✅ GPIO23 configured for previous Jan 25 09:54:57 volumio volumio[1034]: info: gpio-buttons - ✅ GPIO25 configured for next Jan 25 09:54:58 volumio volumio[1034]: info: Volumio Calling Home Jan 25 09:54:58 volumio sudo[1373]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 25 09:54:58 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:54:59 volumio volumio[1034]: info: gpio-buttons - GPIO-Buttons started Jan 25 09:54:59 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root Jan 25 09:54:59 volumio upmpdcli[1298]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Jan 25 09:54:59 volumio upmpdcli[1298]: :2:../src/main.cxx:728::MPD connection failed Jan 25 09:55:05 volumio volumio[1034]: info: MPD Permissions set Jan 25 09:55:05 volumio volumio[1034]: info: MPD Permissions set Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:06 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:06 volumio sudo[1411]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Jan 25 09:55:06 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:06 volumio volumio[1034]: info: Volumio called home Jan 25 09:55:06 volumio kernel: rotary-encoder rotary@6: gray Jan 25 09:55:06 volumio kernel: input: rotary@6 as /devices/platform/rotary@6/input/input0 Jan 25 09:55:06 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:06 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:07 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 25 09:55:08 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 25 09:55:09 volumio sudo[1438]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=16 active_low=true gpio_pull=up keycode=20 Jan 25 09:55:09 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:09 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:09 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:09 volumio kernel: input: button@10 as /devices/platform/button@10/input/input1 Jan 25 09:55:09 volumio sudo[1438]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:09 volumio volumio[1034]: info: Starting Shairport Sync Jan 25 09:55:09 volumio upmpdcli[1298]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Jan 25 09:55:09 volumio upmpdcli[1298]: :2:../src/main.cxx:728::MPD connection failed Jan 25 09:55:09 volumio volumio[1034]: info: Starting Shairport Sync Jan 25 09:55:09 volumio volumio[1034]: info: Starting Shairport Sync Jan 25 09:55:10 volumio sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 09:55:10 volumio sudo[1448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 09:55:10 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:10 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:10 volumio sudo[1450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 09:55:10 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 09:55:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 09:55:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 09:55:10 volumio systemd[1]: shairport-sync.service: Consumed 2.630s CPU time. Jan 25 09:55:10 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 25 09:55:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 09:55:10 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:10 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 09:55:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 09:55:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 09:55:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 09:55:10 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:10 volumio systemd[1]: Stopping pydpiper.service - LCD server for Volumio (pydPiper)... Jan 25 09:55:11 volumio volumio[1034]: info: Shairport-Sync Started Jan 25 09:55:11 volumio volumio[1034]: Error adding Membership: Error: addMembership EINVAL Jan 25 09:55:11 volumio volumio[1034]: info: Shairport-Sync Started Jan 25 09:55:11 volumio volumio[1034]: info: Shairport-Sync Started Jan 25 09:55:11 volumio sudo[1500]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 25 09:55:11 volumio sudo[1500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:11 volumio sudo[1500]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:11 volumio mpd[1358]: 2026-01-25T09:55:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 09:55:12 volumio volumio-remote-updater[639]: [2026-01-25 09:55:12] [connect] Successful connection Jan 25 09:55:12 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:12 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 09:55:12 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:12 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:12 volumio sudo[1503]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jan 25 09:55:12 volumio volumio[1034]: info: Completed starting Core Plugins Jan 25 09:55:12 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:12 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:55:12 volumio volumio[1034]: info: ----- MyVolumio plugins startup ---- Jan 25 09:55:12 volumio volumio[1034]: info: ------------------------------------------- Jan 25 09:55:12 volumio volumio[1034]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 25 09:55:12 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:12 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:12 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:13 volumio volumio[1034]: error: MPD error: The expression evaluated to a falsy value: Jan 25 09:55:13 volumio volumio[1034]: assert.ok(self.idling) Jan 25 09:55:13 volumio volumio[1034]: error: The expression evaluated to a falsy value: Jan 25 09:55:13 volumio volumio[1034]: assert.ok(self.idling) Jan 25 09:55:13 volumio volumio[1034]: error: updateQueue error: null Jan 25 09:55:13 volumio volumio-remote-updater[639]: [2026-01-25 09:55:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769331312 101 Jan 25 09:55:13 volumio volumio[1034]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 11 Jan 25 09:55:13 volumio volumio[1034]: info: MPD running with PID1358 Jan 25 09:55:13 volumio volumio[1034]: ,establishing connection Jan 25 09:55:13 volumio volumio[1034]: error: updateQueue error: null Jan 25 09:55:13 volumio python[1249]: Exiting... Jan 25 09:55:13 volumio python[1249]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_fixed.fnt Jan 25 09:55:13 volumio python[1249]: Connected to Volumio Web Service Jan 25 09:55:13 volumio python[1249]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_lcd.fnt Jan 25 09:55:13 volumio python[1249]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/BigFont_10x16_fixed.fnt Jan 25 09:55:13 volumio python[1249]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/upperasciiwide_3x5_fixed.fnt Jan 25 09:55:13 volumio python[1249]: Shutting down threads Jan 25 09:55:14 volumio systemd[1]: pydpiper.service: Deactivated successfully. Jan 25 09:55:14 volumio systemd[1]: Stopped pydpiper.service - LCD server for Volumio (pydPiper). Jan 25 09:55:14 volumio systemd[1]: pydpiper.service: Consumed 9.670s CPU time. Jan 25 09:55:14 volumio systemd[1]: Started pydpiper.service - LCD server for Volumio (pydPiper). Jan 25 09:55:14 volumio systemd[1]: pydpiper-kick.service: Deactivated successfully. Jan 25 09:55:14 volumio systemd[1]: Finished pydpiper-kick.service - Kick pydPiper after boot (2x restart). Jan 25 09:55:14 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Jan 25 09:55:14 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Jan 25 09:55:14 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 25 09:55:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 25 09:55:14 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 25 09:55:14 volumio systemd[1]: Startup finished in 25.953s (kernel) + 1min 20.840s (userspace) = 1min 46.793s. Jan 25 09:55:15 volumio sudo[1513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 25 09:55:15 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:15 volumio sudo[1514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 25 09:55:15 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 09:55:16 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 25 09:55:16 volumio mpd_monitor.sh[1517]: MPD Monitor Service: Starting MPD Monitor Service Jan 25 09:55:16 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:16 volumio volumio[1034]: info: Successfully started MPD Monitor Jan 25 09:55:16 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Jan 25 09:55:16 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Jan 25 09:55:16 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Jan 25 09:55:16 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Jan 25 09:55:16 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Jan 25 09:55:16 volumio mpd_monitor.sh[1520]: MPD Monitor Service: Starting MPD Monitor Service Jan 25 09:55:16 volumio volumio[1034]: info: Successfully started MPD Monitor Jan 25 09:55:18 volumio python[1509]: pydPiper starting Jan 25 09:55:18 volumio python[1509]: Loading display controller Jan 25 09:55:18 volumio python[1509]: Loading music controller Jan 25 09:55:18 volumio python[1509]: Starting new HTTP connection (1): localhost:3000 Jan 25 09:55:18 volumio volumio[1034]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.32.5 Engine version: 3 Transport: polling Total Clients: 12 Jan 25 09:55:18 volumio python[1509]: http://localhost:3000 "GET /socket.io/?transport=polling&EIO=3&t=1769331318.1852543 HTTP/1.1" 200 101 Jan 25 09:55:18 volumio python[1509]: Connected to Volumio3 Jan 25 09:55:18 volumio python[1509]: Connected to Volumio Web Service Jan 25 09:55:18 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:18 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:18 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:18 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:18 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:18 volumio volumio[1034]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 25 09:55:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:19 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:19 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:19 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:19 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:20 volumio python[1509]: Music Controller Starting Jan 25 09:55:20 volumio python[1509]: Loading app/pages/pages_lcd_16x2.py as page file Jan 25 09:55:20 volumio python[1509]: Initializing current system status update process. System status will update every five minutes Jan 25 09:55:20 volumio python[1509]: Weather service not enabled Jan 25 09:55:20 volumio python[1509]: Weather service not enabled Jan 25 09:55:20 volumio python[1509]: System status: Temp 30°c, Disk space remaining 92%, IP address uid Jan 25 09:55:20 volumio python[1509]: Loading image progbar Jan 25 09:55:20 volumio python[1509]: v out of range with value -1. Should have been between 0 and 100 Jan 25 09:55:20 volumio python[1509]: Trying to add widget playartist to sequence seqPlay but widget was not found Jan 25 09:55:20 volumio python[1509]: Trying to add widget playalbum to sequence seqPlay but widget was not found Jan 25 09:55:20 volumio python[1509]: Trying to add widget playartist_radio to sequence seqPlay but widget was not found Jan 25 09:55:20 volumio python[1509]: Trying to add widget playalbum_radio to sequence seqPlay but widget was not found Jan 25 09:55:20 volumio python[1509]: Trying to add widget playtitle_radio to sequence seqPlay but widget was not found Jan 25 09:55:22 volumio ntpd[823]: CLOCK: time stepped by 0.398015 Jan 25 09:55:22 volumio ntpd[823]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 25 09:55:22 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:22 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:22 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:22 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:22 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:22 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:22 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:22 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:22 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:22 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:25 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:25 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:25 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:25 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:25 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:25 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:25 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:25 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:25 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:25 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:25 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:25 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:25 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:25 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:25 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:26 volumio upmpdcli[1579]: writing RSA key Jan 25 09:55:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:27 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetQueue Jan 25 09:55:27 volumio volumio[1034]: info: CoreStateMachine::getQueue Jan 25 09:55:27 volumio volumio[1034]: info: CorePlayQueue::getQueue Jan 25 09:55:27 volumio volumio[1034]: info: CoreCommandRouter::volumioGetState Jan 25 09:55:27 volumio volumio[1034]: info: CorePlayQueue::getTrack 0 Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 25 09:55:33 volumio volumio[1034]: error: MyVolumio Plugin failed to start in a timely fashion Jan 25 09:55:33 volumio volumio[1034]: [Metrics] CommandRouter: 70s 527.34ms Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::volumiosetStartupVolume Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::Close All Modals sent Jan 25 09:55:33 volumio volumio[1034]: info: CoreCommandRouter::Close All Modals sent Jan 25 09:55:34 volumio volumio[1034]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 09:55:34 volumio volumio[1034]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 25 09:55:34 volumio volumio[1034]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 25 09:55:34 volumio volumio[1034]: at new NodeError (node:internal/errors:405:5) Jan 25 09:55:34 volumio volumio[1034]: at assert (node:internal/assert:14:11) Jan 25 09:55:34 volumio volumio[1034]: at internalConnectMultiple (node:net:1118:3) Jan 25 09:55:34 volumio volumio[1034]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 25 09:55:34 volumio volumio[1034]: at listOnTimeout (node:internal/timers:575:11) Jan 25 09:55:34 volumio volumio[1034]: at process.processTimers (node:internal/timers:514:7) { Jan 25 09:55:34 volumio volumio[1034]: code: 'ERR_INTERNAL_ASSERTION' Jan 25 09:55:34 volumio volumio[1034]: } Jan 25 09:55:34 volumio volumio[1034]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 09:55:37 volumio sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 09:54' Jan 25 09:55:37 volumio sudo[1633]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"