Feb 10 18:33:23 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 10 18:33:23 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 10 18:33:23 volumio systemd[1]: setdatetime-helper.service: Consumed 3.057s CPU time. Feb 10 18:33:23 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Feb 10 18:33:23 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Feb 10 18:33:23 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Feb 10 18:33:23 volumio smbd[997]: [2026/02/10 18:33:23.248049, 0] ../../source3/smbd/server.c:1741(main) Feb 10 18:33:23 volumio smbd[997]: smbd version 4.17.8-Raspbian started. Feb 10 18:33:23 volumio smbd[997]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 10 18:33:23 volumio nmbd[949]: [2026/02/10 18:33:23.343934, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 10 18:33:23 volumio nmbd[949]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.88 for name WORKGROUP<1d>. Feb 10 18:33:23 volumio nmbd[949]: This response was from IP 192.168.0.109, reporting an IP address of 192.168.0.109. Feb 10 18:33:23 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Feb 10 18:33:23 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Feb 10 18:33:23 volumio winbindd[981]: [2026/02/10 18:33:23.587555, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Feb 10 18:33:23 volumio winbindd[981]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'VOLUMIO' failed 'NT_STATUS_IO_TIMEOUT' Feb 10 18:33:23 volumio ntpd[821]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 10 18:33:23 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 10 18:33:23 volumio ntpd[821]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 10 18:33:23 volumio ntpd[821]: DNS: Pool taking: 195.46.37.22 Feb 10 18:33:23 volumio ntpd[821]: DNS: Pool skipping: 212.127.78.21 Feb 10 18:33:23 volumio ntpd[821]: DNS: Pool taking: 178.215.228.24 Feb 10 18:33:23 volumio ntpd[821]: DNS: Pool taking: 89.161.47.136 Feb 10 18:33:23 volumio ntpd[821]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 10 18:33:24 volumio wireless.js[646]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Feb 10 18:33:24 volumio wireless.js[646]: WIRELESS.JS: Wired network status changed to: ---connected--- Feb 10 18:33:24 volumio wireless.js[646]: WIRELESS.JS: Wireless.js initializing wireless flow Feb 10 18:33:24 volumio wireless.js[646]: WIRELESS.JS: Cleaning previous... Feb 10 18:33:24 volumio wireless.js[646]: WIRELESS.JS: No wireless interface, exiting Feb 10 18:33:24 volumio systemd[1]: wireless.service: Killing process 1035 (sh) with signal SIGKILL. Feb 10 18:33:24 volumio systemd[1]: wireless.service: Killing process 1036 (systemctl) with signal SIGKILL. Feb 10 18:33:24 volumio systemd[1]: wireless.service: Failed with result 'protocol'. Feb 10 18:33:24 volumio systemd[1]: Failed to start wireless.service - Wireless Services. Feb 10 18:33:24 volumio systemd[1]: wireless.service: Consumed 13.355s CPU time. Feb 10 18:33:25 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 10 18:33:25 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Feb 10 18:33:25 volumio systemd[1]: Starting pydpiper-kick.service - Kick pydPiper after boot (2x restart)... Feb 10 18:33:25 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Feb 10 18:33:25 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Feb 10 18:33:25 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Feb 10 18:33:25 volumio systemd[1]: Received SIGRTMIN+21 from PID 274 (n/a). Feb 10 18:33:25 volumio systemd[1]: Received SIGRTMIN+21 from PID 274 (n/a). Feb 10 18:33:25 volumio volumio-cpu-tweak[1042]: Setting RT Priority for mpd Feb 10 18:33:25 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 10 18:33:25 volumio volumio-cpu-tweak[1058]: pid 35's current scheduling policy: SCHED_OTHER Feb 10 18:33:25 volumio volumio-cpu-tweak[1058]: pid 35's current scheduling priority: 0 Feb 10 18:33:25 volumio volumio-cpu-tweak[1042]: Setting MPD Affinity Feb 10 18:33:25 volumio volumio-cpu-tweak[1059]: pid 3's current affinity mask: f Feb 10 18:33:25 volumio systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Feb 10 18:33:25 volumio systemd[1]: Reached target getty.target - Login Prompts. Feb 10 18:33:25 volumio volumio-cpu-tweak[1042]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 10 18:33:25 volumio volumio-cpu-tweak[1042]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 10 18:33:25 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Feb 10 18:33:26 volumio volumio-remote-updater[643]: [2026-02-10 18:33:26] [info] asio async_connect error: asio.system:111 (Connection refused) Feb 10 18:33:26 volumio volumio-remote-updater[643]: [2026-02-10 18:33:26] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Feb 10 18:33:26 volumio volumio-remote-updater[643]: [2026-02-10 18:33:26] [error] handle_connect error: Connection refused Feb 10 18:33:26 volumio python[780]: pydPiper starting Feb 10 18:33:26 volumio winbindd[990]: [2026/02/10 18:33:26.534316, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Feb 10 18:33:26 volumio winbindd[990]: Could not write result Feb 10 18:33:26 volumio python[780]: Loading display controller Feb 10 18:33:26 volumio python[780]: Loading music controller Feb 10 18:33:26 volumio python[780]: Starting new HTTP connection (1): localhost:3000 Feb 10 18:33:26 volumio python[780]: Exception in thread Thread-2 (run): Feb 10 18:33:26 volumio python[780]: Traceback (most recent call last): Feb 10 18:33:26 volumio python[780]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 276, in connect Feb 10 18:33:26 volumio python[780]: self.eio.connect(url, headers=headers, transports=transports, Feb 10 18:33:26 volumio python[780]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/engineio/client.py", line 194, in connect Feb 10 18:33:26 volumio python[780]: return getattr(self, '_connect_' + self.transports[0])( Feb 10 18:33:26 volumio python[780]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 10 18:33:26 volumio python[780]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/engineio/client.py", line 300, in _connect_polling Feb 10 18:33:26 volumio python[780]: raise exceptions.ConnectionError( Feb 10 18:33:26 volumio python[780]: engineio.exceptions.ConnectionError: Connection refused by the server Feb 10 18:33:26 volumio python[780]: During handling of the above exception, another exception occurred: Feb 10 18:33:26 volumio python[780]: Traceback (most recent call last): Feb 10 18:33:26 volumio python[780]: File "/usr/lib/python3.11/threading.py", line 1038, in _bootstrap_inner Feb 10 18:33:26 volumio python[780]: self.run() Feb 10 18:33:26 volumio python[780]: File "/usr/lib/python3.11/threading.py", line 975, in run Feb 10 18:33:26 volumio python[780]: self._target(*self._args, **self._kwargs) Feb 10 18:33:26 volumio python[780]: File "/home/volumio/pydPiper_test/pydPiper/app/sources/musicdata_volumio.py", line 193, in run Feb 10 18:33:26 volumio python[780]: self.sio.connect(f"http://{self.server}:{self.port}") Feb 10 18:33:26 volumio python[780]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 279, in connect Feb 10 18:33:26 volumio python[780]: self._trigger_event( Feb 10 18:33:26 volumio python[780]: File "/home/volumio/pydpiper-venv/lib/python3.11/site-packages/socketio/client.py", line 550, in _trigger_event Feb 10 18:33:26 volumio python[780]: return self.handlers[namespace][event](*args) Feb 10 18:33:26 volumio python[780]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 10 18:33:26 volumio python[780]: TypeError: musicdata_volumio3.init_callbacks..connect_error() takes 0 positional arguments but 1 was given Feb 10 18:33:28 volumio python[780]: Music Controller Starting Feb 10 18:33:28 volumio python[780]: Loading app/pages/pages_lcd_16x2.py as page file Feb 10 18:33:28 volumio python[780]: Initializing current system status update process. System status will update every five minutes Feb 10 18:33:28 volumio python[780]: Weather service not enabled Feb 10 18:33:28 volumio python[780]: Weather service not enabled Feb 10 18:33:28 volumio python[780]: System status: Temp 29°c, Disk space remaining 92%, IP address uid Feb 10 18:33:28 volumio python[780]: Loading image progbar Feb 10 18:33:28 volumio python[780]: v out of range with value -1. Should have been between 0 and 100 Feb 10 18:33:29 volumio python[780]: Trying to add widget playartist to sequence seqPlay but widget was not found Feb 10 18:33:29 volumio python[780]: Trying to add widget playalbum to sequence seqPlay but widget was not found Feb 10 18:33:29 volumio python[780]: Trying to add widget playartist_radio to sequence seqPlay but widget was not found Feb 10 18:33:29 volumio python[780]: Trying to add widget playalbum_radio to sequence seqPlay but widget was not found Feb 10 18:33:29 volumio python[780]: Trying to add widget playtitle_radio to sequence seqPlay but widget was not found Feb 10 18:33:29 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 10 18:33:29 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 18:33:30 volumio ntpd[821]: CLOCK: time stepped by 0.236042 Feb 10 18:33:30 volumio ntpd[821]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 10 18:33:30 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 18:33:30 volumio upmpdcli[1080]: Could not open config: /tmp/upmpdcli.conf Feb 10 18:33:30 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 10 18:33:30 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 10 18:33:31 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:33:31 volumio volumio[1039]: info: ----- Volumio3 ---- Feb 10 18:33:31 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:33:31 volumio volumio[1039]: info: ----- System startup ---- Feb 10 18:33:31 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:33:34 volumio volumio[1039]: info: MYVOLUMIO Environment detected Feb 10 18:33:34 volumio volumio[1039]: info: Plugin folders cleanup Feb 10 18:33:34 volumio volumio[1039]: info: Scanning into folder /volumio/app/plugins/ Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category audio_interface Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category miscellanea Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category music_service Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category plugins.json Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category system_controller Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category user_interface Feb 10 18:33:34 volumio volumio[1039]: info: Scanning into folder /data/plugins/ Feb 10 18:33:34 volumio volumio[1039]: info: Scanning category system_hardware Feb 10 18:33:34 volumio volumio[1039]: info: Plugin folders cleanup completed Feb 10 18:33:34 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:33:34 volumio volumio[1039]: info: ----- Core plugins startup ---- Feb 10 18:33:34 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:33:34 volumio volumio[1039]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 18:33:34 volumio volumio[1039]: info: Adding plugin upnp to MyMusic Plugins Feb 10 18:33:34 volumio volumio[1039]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 18:33:34 volumio volumio[1039]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 18:33:34 volumio volumio[1039]: info: Loading plugins from folder /data/plugins/ Feb 10 18:33:34 volumio volumio[1039]: info: Loading plugin "system"... Feb 10 18:33:34 volumio volumio[1039]: info: Loading plugin "appearance"... Feb 10 18:33:36 volumio volumio-remote-updater[643]: [2026-02-10 18:33:36] [connect] Successful connection Feb 10 18:33:38 volumio volumio[1039]: info: Loading plugin "network"... Feb 10 18:33:38 volumio volumio[1039]: info: Refreshing Cached IP Addresses Feb 10 18:33:38 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 10 18:33:38 volumio volumio[1039]: info: Loading plugin "services"... Feb 10 18:33:38 volumio volumio[1039]: info: Loading plugin "alsa_controller"... Feb 10 18:33:38 volumio sudo[1095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 18:33:38 volumio sudo[1106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 18:33:38 volumio sudo[1097]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 18:33:38 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:38 volumio sudo[1106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:38 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:38 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:38 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:38 volumio sudo[1106]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:38 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 18:33:38 volumio volumio[1039]: info: Loading plugin "wizard"... Feb 10 18:33:38 volumio volumio[1039]: info: Loading plugin "networkfs"... Feb 10 18:33:39 volumio volumio[1039]: info: Starting Udev Watcher for removable devices Feb 10 18:33:39 volumio volumio[1039]: info: Ignoring mount for partition: boot Feb 10 18:33:39 volumio volumio[1039]: info: Ignoring mount for partition: volumio Feb 10 18:33:39 volumio volumio[1039]: info: Ignoring mount for partition: volumio_data Feb 10 18:33:39 volumio volumio[1039]: info: Ignoring mount for partition: INTERNAL Feb 10 18:33:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 18:33:39 volumio volumio[1039]: info: Loading plugin "volumio_command_line_client"... Feb 10 18:33:39 volumio volumio[1039]: info: Loading plugin "upnp"... Feb 10 18:33:39 volumio sudo[1147]: 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 Feb 10 18:33:39 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:39 volumio volumio[1039]: info: [1770744819363] Starting Upmpd Daemon Feb 10 18:33:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 18:33:39 volumio volumio[1039]: info: Loading plugin "my_music"... Feb 10 18:33:39 volumio volumio[1039]: info: Loading plugin "mpd"... Feb 10 18:33:39 volumio kernel: netfs: FS-Cache loaded Feb 10 18:33:39 volumio kernel: Key type cifs.spnego registered Feb 10 18:33:39 volumio kernel: Key type cifs.idmap registered Feb 10 18:33:39 volumio kernel: CIFS: Attempting to mount //192.168.0.109/music/muza Feb 10 18:33:40 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:40 volumio volumio[1039]: info: Loading plugin "upnp_browser"... Feb 10 18:33:41 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 10 18:33:41 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 10 18:33:41 volumio systemd[1]: setdatetime-helper.service: Consumed 2.257s CPU time. Feb 10 18:33:44 volumio volumio[1039]: info: Starting UPNP Browser Feb 10 18:33:44 volumio volumio[1039]: info: Loading plugin "alarm-clock"... Feb 10 18:33:44 volumio volumio[1039]: info: Loading plugin "airplay_emulation"... Feb 10 18:33:44 volumio volumio[1039]: info: Starting Shairport Sync Feb 10 18:33:44 volumio volumio[1039]: info: Loading plugin "last_100"... Feb 10 18:33:44 volumio volumio[1039]: info: Loading plugin "webradio"... Feb 10 18:33:44 volumio volumio[1039]: info: Loading plugin "i2s_dacs"... Feb 10 18:33:44 volumio volumio[1039]: info: I2S DAC not set, start Auto-detection Feb 10 18:33:45 volumio volumio[1039]: info: Loading plugin "volumiodiscovery"... Feb 10 18:33:45 volumio volumio[1039]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 18:33:45 volumio node[1039]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 18:33:45 volumio volumio[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 18:33:45 volumio node[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 18:33:45 volumio volumio[1039]: *** WARNING *** For more information see Feb 10 18:33:45 volumio node[1039]: *** WARNING *** For more information see Feb 10 18:33:45 volumio volumio[1039]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 18:33:45 volumio node[1039]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 18:33:45 volumio volumio[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 18:33:45 volumio node[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 18:33:45 volumio volumio[1039]: *** WARNING *** For more information see Feb 10 18:33:45 volumio node[1039]: *** WARNING *** For more information see Feb 10 18:33:45 volumio volumio[1039]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 18:33:45 volumio volumio[1039]: info: Discovery: Started advertising with name: Volumio Feb 10 18:33:45 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 18:33:45 volumio volumio[1039]: info: Loading plugin "gpio-buttons"... Feb 10 18:33:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 10 18:33:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 18:33:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 18:33:45 volumio upmpdcli[1198]: Could not open config: /tmp/upmpdcli.conf Feb 10 18:33:45 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 10 18:33:45 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 10 18:33:45 volumio volumio[1039]: gpiox_arm32 V2.2.1 Feb 10 18:33:46 volumio volumio[1039]: info: gpio-buttons - GPIO-Buttons initialized Feb 10 18:33:46 volumio volumio[1039]: info: Loading plugin "outputs"... Feb 10 18:33:46 volumio volumio[1039]: info: Loading plugin "albumart"... Feb 10 18:33:46 volumio volumio[1039]: info: Plugin example_plugin is not enabled Feb 10 18:33:46 volumio volumio[1039]: info: Loading plugin "inputs"... Feb 10 18:33:46 volumio volumio[1039]: info: Loading plugin "updater_comm"... Feb 10 18:33:47 volumio volumio[1039]: info: Plugin mpdemulation is not enabled Feb 10 18:33:47 volumio volumio[1039]: info: Loading plugin "rest_api"... Feb 10 18:33:47 volumio volumio[1039]: info: Loading plugin "websocket"... Feb 10 18:33:47 volumio volumio[1039]: info: Starting Socket.io Server version 1.7.4 Feb 10 18:33:47 volumio volumio[1039]: info: Loading plugin "gpio_control"... Feb 10 18:33:47 volumio volumio[1039]: gpiox_arm32 V2.2.1 Feb 10 18:33:49 volumio volumio[1199]: Forking 3 albumart workers Feb 10 18:33:50 volumio volumio[1039]: info: Applying required configuration parameters for plugin gpio_control Feb 10 18:33:50 volumio volumio[1039]: info: Loading plugin "rotaryencoder2"... Feb 10 18:33:50 volumio systemd[1]: Stopping pydpiper.service - LCD server for Volumio (pydPiper)... Feb 10 18:33:51 volumio volumio-remote-updater[643]: [2026-02-10 18:33:51] [connect] Successful connection Feb 10 18:33:53 volumio python[780]: Exiting... Feb 10 18:33:53 volumio python[780]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_fixed.fnt Feb 10 18:33:53 volumio python[780]: Connected to Volumio Web Service Feb 10 18:33:53 volumio python[780]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_lcd.fnt Feb 10 18:33:53 volumio python[780]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/BigFont_10x16_fixed.fnt Feb 10 18:33:53 volumio python[780]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/upperasciiwide_3x5_fixed.fnt Feb 10 18:33:53 volumio python[780]: Shutting down threads Feb 10 18:33:54 volumio systemd[1]: pydpiper.service: Deactivated successfully. Feb 10 18:33:54 volumio systemd[1]: Stopped pydpiper.service - LCD server for Volumio (pydPiper). Feb 10 18:33:54 volumio systemd[1]: pydpiper.service: Consumed 12.109s CPU time. Feb 10 18:33:54 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Feb 10 18:33:54 volumio systemd[1]: Started pydpiper.service - LCD server for Volumio (pydPiper). Feb 10 18:33:54 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Feb 10 18:33:54 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Feb 10 18:33:54 volumio volumio[1039]: info: Loading i18n strings for locale pl Feb 10 18:33:55 volumio volumio[1039]: Updating browse sources language Feb 10 18:33:55 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 18:33:55 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 10 18:33:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 18:33:56 volumio volumio[1039]: info: CoreCommandRouter::initPlayerControls Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 18:33:57 volumio volumio[1039]: Express server listening on port 3000 Feb 10 18:33:57 volumio volumio[1039]: [Metrics] WebUI: 26s 983.31ms Feb 10 18:33:57 volumio volumio[1039]: info: CoreStateMachine::resetVolumioState Feb 10 18:33:57 volumio volumio[1039]: info: CoreStateMachine::getcurrentVolume Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 18:33:57 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:33:57 volumio python[1255]: pydPiper starting Feb 10 18:33:58 volumio python[1255]: Loading display controller Feb 10 18:33:58 volumio python[1255]: Loading music controller Feb 10 18:33:58 volumio python[1255]: Starting new HTTP connection (1): localhost:3000 Feb 10 18:33:58 volumio sudo[1265]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 18:33:58 volumio sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 18:33:58 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:58 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:58 volumio volumio[1039]: info: Volumio Network Manager: Network status updated: 1 Feb 10 18:33:58 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:58 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:58 volumio volumio[1039]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 10 18:33:58 volumio volumio[1039]: wlan0 Interface doesn't support scanning. Feb 10 18:33:58 volumio volumio[1039]: info: Cannot use regular scanning, forcing with ap-force Feb 10 18:33:59 volumio sudo[1283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 10 18:33:59 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:33:59 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Feb 10 18:33:59 volumio volumio[1039]: command failed: No such device (-19) Feb 10 18:33:59 volumio volumio[1039]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 10 18:33:59 volumio volumio[1039]: command failed: No such device (-19) Feb 10 18:34:00 volumio python[1255]: Music Controller Starting Feb 10 18:34:00 volumio python[1255]: Loading app/pages/pages_lcd_16x2.py as page file Feb 10 18:34:00 volumio python[1255]: Initializing current system status update process. System status will update every five minutes Feb 10 18:34:00 volumio python[1255]: Weather service not enabled Feb 10 18:34:00 volumio python[1255]: Weather service not enabled Feb 10 18:34:00 volumio volumio[1039]: info: VolumeController:: Volume=100 Mute =false Feb 10 18:34:00 volumio python[1255]: System status: Temp 32°c, Disk space remaining 92%, IP address uid Feb 10 18:34:00 volumio volumio[1039]: info: CoreStateMachine::pushState Feb 10 18:34:00 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:00 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 18:34:00 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Feb 10 18:34:00 volumio python[1255]: Loading image progbar Feb 10 18:34:00 volumio python[1255]: v out of range with value -1. Should have been between 0 and 100 Feb 10 18:34:00 volumio python[1255]: Trying to add widget playartist to sequence seqPlay but widget was not found Feb 10 18:34:00 volumio python[1255]: Trying to add widget playalbum to sequence seqPlay but widget was not found Feb 10 18:34:00 volumio python[1255]: Trying to add widget playartist_radio to sequence seqPlay but widget was not found Feb 10 18:34:00 volumio python[1255]: Trying to add widget playalbum_radio to sequence seqPlay but widget was not found Feb 10 18:34:00 volumio python[1255]: Trying to add widget playtitle_radio to sequence seqPlay but widget was not found Feb 10 18:34:00 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Feb 10 18:34:00 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Feb 10 18:34:00 volumio volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 18:34:00 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 10 18:34:00 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 18:34:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 10 18:34:00 volumio volumio[1039]: 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 Feb 10 18:34:00 volumio upmpdcli[1309]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.0.88 port 49152 IPV6 port 49152 Feb 10 18:34:00 volumio upmpdcli[1309]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Feb 10 18:34:00 volumio upmpdcli[1309]: :2:../src/main.cxx:728::MPD connection failed Feb 10 18:34:01 volumio volumio[1039]: info: Reloading queue from file Feb 10 18:34:01 volumio volumio[1225]: Starting albumart workers Feb 10 18:34:01 volumio volumio[1223]: Starting albumart workers Feb 10 18:34:01 volumio volumio[1039]: info: CoreStateMachine::setRepeat false single undefined Feb 10 18:34:01 volumio volumio[1039]: info: CoreStateMachine::pushState Feb 10 18:34:01 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 18:34:01 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Feb 10 18:34:01 volumio volumio[1039]: info: CoreStateMachine::setRandom null Feb 10 18:34:01 volumio volumio[1039]: info: CoreStateMachine::pushState Feb 10 18:34:01 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:01 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Feb 10 18:34:01 volumio volumio[1039]: info: Setting Device type: Raspberry PI Feb 10 18:34:01 volumio volumio[1039]: 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 Feb 10 18:34:01 volumio volumio[1039]: info: VolumeController:: Volume=100 Mute =false Feb 10 18:34:01 volumio volumio[1039]: info: CoreStateMachine::pushState Feb 10 18:34:01 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:01 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Feb 10 18:34:01 volumio python[1255]: http://localhost:3000 "GET /socket.io/?transport=polling&EIO=3&t=1770744838.0370696 HTTP/1.1" 200 101 Feb 10 18:34:02 volumio volumio[1039]: 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: 3 Feb 10 18:34:02 volumio volumio[1039]: info: Discovery: adding b1384ca2-a9a4-4b11-af6f-04cd5c3872a6 Feb 10 18:34:02 volumio volumio[1039]: info: Discovery: Found device Volumio Feb 10 18:34:02 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:02 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:02 volumio sudo[1329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 18:34:02 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:02 volumio volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 10 18:34:02 volumio volumio[1039]: info: Discovery: this is already registered, b1384ca2-a9a4-4b11-af6f-04cd5c3872a6 Feb 10 18:34:02 volumio volumio[1039]: info: Discovery: Found device Volumio Feb 10 18:34:02 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:02 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:02 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:02 volumio volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 10 18:34:02 volumio volumio[1224]: Starting albumart workers Feb 10 18:34:02 volumio upmpdcli[1309]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Feb 10 18:34:02 volumio upmpdcli[1309]: :2:../src/main.cxx:728::MPD connection failed Feb 10 18:34:03 volumio volumio[1039]: info: Discovery: adding 7bd79a2c-e52f-458f-b231-4b6b7fdfe306 Feb 10 18:34:03 volumio volumio[1039]: info: Discovery: Found device Volumio4 Feb 10 18:34:03 volumio volumio[1039]: info: Discovery: Connecting to remote: 192.168.0.125 Feb 10 18:34:03 volumio volumio[1039]: 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: 6 Feb 10 18:34:03 volumio volumio[1039]: info: Upmpdcli Daemon Started Feb 10 18:34:04 volumio volumio[1039]: info: Completed loading Core Plugins Feb 10 18:34:04 volumio volumio[1039]: info: Preparing to generate the ALSA configuration file Feb 10 18:34:04 volumio python[1255]: Connected to Volumio3 Feb 10 18:34:04 volumio python[1255]: Connected to Volumio Web Service Feb 10 18:34:05 volumio volumio[1039]: info: Discovery: Connected to remote: 192.168.0.125 Feb 10 18:34:05 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:05 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:05 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:05 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:05 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:05 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:05 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:05 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:05 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:05 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:06 volumio volumio-remote-updater[643]: [2026-02-10 18:34:06] [connect] Successful connection Feb 10 18:34:06 volumio volumio[1039]: info: Asound.conf file unchanged, so no further update is needed Feb 10 18:34:06 volumio volumio[1039]: info: Output device has changed, restarting MPD Feb 10 18:34:06 volumio volumio[1039]: info: Output device has changed, restarting Shairport Sync Feb 10 18:34:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:06 volumio upmpdcli[1309]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Feb 10 18:34:06 volumio upmpdcli[1309]: :2:../src/main.cxx:728::MPD connection failed Feb 10 18:34:06 volumio sudo[1347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 18:34:06 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:06 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:07 volumio sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 18:34:07 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:07 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 18:34:07 volumio volumio[1039]: info: ___________ START PLUGINS ___________ Feb 10 18:34:07 volumio volumio[1039]: info: ControllerMpd::onStart: Initializing MPD Feb 10 18:34:07 volumio volumio[1039]: info: Creating MPD Configuration file Feb 10 18:34:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 18:34:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 18:34:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 18:34:07 volumio volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 18:34:07 volumio volumio[1039]: info: [1770744847513] CoreMusicLibrary::Adding element Serwery Mediów Feb 10 18:34:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 18:34:07 volumio volumio[1039]: info: UPNP Browser: Client initialized successfully Feb 10 18:34:07 volumio sudo[1370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 18:34:07 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:07 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:07 volumio sudo[1371]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 18:34:07 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 18:34:07 volumio sudo[1376]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 18:34:07 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:07 volumio sudo[1373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 18:34:07 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 18:34:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 18:34:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 18:34:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 18:34:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 18:34:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 18:34:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 18:34:08 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:08 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 18:34:08 volumio volumio[1039]: info: [1770744848653] CoreMusicLibrary::Adding element Last_100 Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 18:34:08 volumio volumio[1039]: info: [1770744848679] CoreMusicLibrary::Adding element Webradio Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 18:34:08 volumio sudo[1384]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 18:34:08 volumio sudo[1384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 18:34:08 volumio sudo[1389]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 18:34:08 volumio sudo[1384]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 18:34:08 volumio volumio[1039]: info: Initializing BBC Radios Feb 10 18:34:09 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 18:34:09 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:09 volumio volumio[1039]: info: gpio-buttons - Initializing GPIO triggers... Feb 10 18:34:09 volumio volumio[1039]: info: gpio-buttons - ✅ GPIO24 configured for playPause Feb 10 18:34:09 volumio volumio[1039]: info: gpio-buttons - ✅ GPIO23 configured for previous Feb 10 18:34:09 volumio volumio[1039]: info: gpio-buttons - ✅ GPIO25 configured for next Feb 10 18:34:10 volumio volumio[1039]: info: Volumio Calling Home Feb 10 18:34:10 volumio sudo[1428]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 10 18:34:10 volumio sudo[1428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:10 volumio sudo[1428]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:10 volumio volumio[1039]: info: gpio-buttons - GPIO-Buttons started Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:16 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:16 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:16 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:16 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:16 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:16 volumio upmpdcli[1309]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Feb 10 18:34:16 volumio upmpdcli[1309]: :2:../src/main.cxx:728::MPD connection failed Feb 10 18:34:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:17 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:17 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:17 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:17 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:17 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:17 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:17 volumio volumio[1039]: info: MPD Permissions set Feb 10 18:34:17 volumio volumio[1039]: info: MPD Permissions set Feb 10 18:34:18 volumio volumio[1039]: info: Discovery: Disconnected from remote: 192.168.0.125 Feb 10 18:34:18 volumio sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=5 relative_axis=true steps-per-period=1 Feb 10 18:34:18 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:18 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:18 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:18 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:18 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:18 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:18 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:18 volumio kernel: rotary-encoder rotary@6: gray Feb 10 18:34:18 volumio kernel: input: rotary@6 as /devices/platform/rotary@6/input/input0 Feb 10 18:34:18 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:18 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:18 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:18 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:18 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:19 volumio systemd[1]: Stopping pydpiper.service - LCD server for Volumio (pydPiper)... Feb 10 18:34:20 volumio volumio[1039]: info: Volumio called home Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio mpd[1390]: 2026-02-10T18:34:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:20 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:20 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:20 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:20 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:20 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:20 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:20 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 18:34:20 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:20 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:21 volumio volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 10 18:34:21 volumio volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 10 18:34:21 volumio sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=16 active_low=true gpio_pull=up keycode=20 Feb 10 18:34:21 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:21 volumio kernel: input: button@10 as /devices/platform/button@10/input/input1 Feb 10 18:34:21 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:21 volumio volumio-remote-updater[643]: [2026-02-10 18:34:21] [connect] Successful connection Feb 10 18:34:21 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:21 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:21 volumio volumio[1039]: info: Discovery: Connected to remote: 192.168.0.125 Feb 10 18:34:21 volumio volumio[1039]: info: Starting Shairport Sync Feb 10 18:34:21 volumio volumio[1039]: info: Starting Shairport Sync Feb 10 18:34:21 volumio volumio[1039]: info: Starting Shairport Sync Feb 10 18:34:22 volumio sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 18:34:22 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:22 volumio sudo[1542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 18:34:22 volumio sudo[1542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:22 volumio sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 18:34:22 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 18:34:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 18:34:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 18:34:22 volumio systemd[1]: shairport-sync.service: Consumed 2.675s CPU time. Feb 10 18:34:22 volumio volumio[1039]: error: MPD error: The expression evaluated to a falsy value: Feb 10 18:34:22 volumio volumio[1039]: assert.ok(self.idling) Feb 10 18:34:22 volumio volumio[1039]: error: The expression evaluated to a falsy value: Feb 10 18:34:22 volumio volumio[1039]: assert.ok(self.idling) Feb 10 18:34:22 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:22 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:22 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:22 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:22 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:22 volumio volumio[1039]: info: MPD running with PID1390 Feb 10 18:34:22 volumio volumio[1039]: ,establishing connection Feb 10 18:34:22 volumio python[1255]: Exiting... Feb 10 18:34:22 volumio python[1255]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_fixed.fnt Feb 10 18:34:22 volumio python[1255]: Connected to Volumio Web Service Feb 10 18:34:22 volumio python[1255]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/latin1_5x8_lcd.fnt Feb 10 18:34:22 volumio python[1255]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/BigFont_10x16_fixed.fnt Feb 10 18:34:22 volumio python[1255]: Loading font from /home/volumio/pydPiper_test/pydPiper/app/displays/fonts/upperasciiwide_3x5_fixed.fnt Feb 10 18:34:22 volumio python[1255]: Shutting down threads Feb 10 18:34:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 18:34:22 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:22 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 18:34:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 18:34:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 18:34:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 18:34:22 volumio volumio[1039]: error: updateQueue error: null Feb 10 18:34:22 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:22 volumio volumio-remote-updater[643]: [2026-02-10 18:34:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770744861 101 Feb 10 18:34:23 volumio sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 10 18:34:23 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:23 volumio volumio[1039]: 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: 9 Feb 10 18:34:23 volumio systemd[1]: pydpiper.service: Deactivated successfully. Feb 10 18:34:23 volumio systemd[1]: Stopped pydpiper.service - LCD server for Volumio (pydPiper). Feb 10 18:34:23 volumio systemd[1]: pydpiper.service: Consumed 10.549s CPU time. Feb 10 18:34:23 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:23 volumio systemd[1]: Started pydpiper.service - LCD server for Volumio (pydPiper). Feb 10 18:34:23 volumio systemd[1]: pydpiper-kick.service: Deactivated successfully. Feb 10 18:34:23 volumio systemd[1]: Finished pydpiper-kick.service - Kick pydPiper after boot (2x restart). Feb 10 18:34:23 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Feb 10 18:34:23 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Feb 10 18:34:23 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 10 18:34:23 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 10 18:34:23 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 10 18:34:23 volumio systemd[1]: Startup finished in 26.611s (kernel) + 1min 19.586s (userspace) = 1min 46.197s. Feb 10 18:34:23 volumio volumio[1039]: info: Completed starting Core Plugins Feb 10 18:34:23 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:34:23 volumio volumio[1039]: info: ----- MyVolumio plugins startup ---- Feb 10 18:34:23 volumio volumio[1039]: info: ------------------------------------------- Feb 10 18:34:23 volumio volumio[1039]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 18:34:24 volumio volumio[1039]: info: Shairport-Sync Started Feb 10 18:34:24 volumio volumio[1039]: Error adding Membership: Error: addMembership EINVAL Feb 10 18:34:24 volumio volumio[1039]: info: Shairport-Sync Started Feb 10 18:34:24 volumio volumio[1039]: info: Shairport-Sync Started Feb 10 18:34:24 volumio volumio[1039]: error: updateQueue error: null Feb 10 18:34:24 volumio sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 10 18:34:24 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:24 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:24 volumio sudo[1577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 18:34:24 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:24 volumio sudo[1578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 18:34:24 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 18:34:25 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 10 18:34:25 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:25 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Feb 10 18:34:25 volumio mpd_monitor.sh[1583]: MPD Monitor Service: Starting MPD Monitor Service Feb 10 18:34:25 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:25 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:25 volumio volumio[1039]: info: Successfully started MPD Monitor Feb 10 18:34:25 volumio volumio[1039]: info: Successfully started MPD Monitor Feb 10 18:34:25 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:25 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:26 volumio python[1570]: pydPiper starting Feb 10 18:34:26 volumio python[1570]: Loading display controller Feb 10 18:34:26 volumio python[1570]: Loading music controller Feb 10 18:34:26 volumio python[1570]: Starting new HTTP connection (1): localhost:3000 Feb 10 18:34:26 volumio python[1570]: http://localhost:3000 "GET /socket.io/?transport=polling&EIO=3&t=1770744866.867395 HTTP/1.1" 200 101 Feb 10 18:34:26 volumio volumio[1039]: 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: 9 Feb 10 18:34:26 volumio python[1570]: Connected to Volumio3 Feb 10 18:34:26 volumio python[1570]: Connected to Volumio Web Service Feb 10 18:34:27 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:27 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:27 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:27 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:27 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:27 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:27 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:27 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:27 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:28 volumio volumio[1039]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 10 18:34:28 volumio python[1570]: Music Controller Starting Feb 10 18:34:28 volumio python[1570]: Loading app/pages/pages_lcd_16x2.py as page file Feb 10 18:34:28 volumio python[1570]: Initializing current system status update process. System status will update every five minutes Feb 10 18:34:28 volumio python[1570]: Weather service not enabled Feb 10 18:34:28 volumio python[1570]: Weather service not enabled Feb 10 18:34:29 volumio python[1570]: Loading image progbar Feb 10 18:34:29 volumio python[1570]: System status: Temp 34°c, Disk space remaining 92%, IP address uid Feb 10 18:34:29 volumio python[1570]: v out of range with value -1. Should have been between 0 and 100 Feb 10 18:34:29 volumio python[1570]: Trying to add widget playartist to sequence seqPlay but widget was not found Feb 10 18:34:29 volumio python[1570]: Trying to add widget playalbum to sequence seqPlay but widget was not found Feb 10 18:34:29 volumio python[1570]: Trying to add widget playartist_radio to sequence seqPlay but widget was not found Feb 10 18:34:29 volumio python[1570]: Trying to add widget playalbum_radio to sequence seqPlay but widget was not found Feb 10 18:34:29 volumio python[1570]: Trying to add widget playtitle_radio to sequence seqPlay but widget was not found Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:32 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:33 volumio upmpdcli[1639]: writing RSA key Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:36 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 18:34:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:41 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:41 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:43 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:43 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:43 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:43 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:43 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:43 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:43 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:43 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:43 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:43 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:45 volumio volumio[1039]: error: MyVolumio Plugin failed to start in a timely fashion Feb 10 18:34:45 volumio volumio[1039]: [Metrics] CommandRouter: 73s 225.62ms Feb 10 18:34:45 volumio volumio[1039]: info: CoreCommandRouter::volumiosetStartupVolume Feb 10 18:34:45 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:34:45 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:34:45 volumio volumio[1039]: info: CoreCommandRouter::Close All Modals sent Feb 10 18:34:45 volumio volumio[1039]: info: CoreCommandRouter::Close All Modals sent Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreStateMachine::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getQueue Feb 10 18:34:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Feb 10 18:34:53 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Feb 10 18:34:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 10 18:34:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 10 18:34:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 10 18:34:54 volumio volumio[1039]: info: BOOT COMPLETED Feb 10 18:34:54 volumio volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 18:34:54 volumio volumio[1039]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 10 18:34:54 volumio volumio[1039]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 10 18:34:54 volumio volumio[1039]: at new NodeError (node:internal/errors:405:5) Feb 10 18:34:54 volumio volumio[1039]: at assert (node:internal/assert:14:11) Feb 10 18:34:54 volumio volumio[1039]: at internalConnectMultiple (node:net:1118:3) Feb 10 18:34:54 volumio volumio[1039]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 10 18:34:54 volumio volumio[1039]: at listOnTimeout (node:internal/timers:575:11) Feb 10 18:34:54 volumio volumio[1039]: at process.processTimers (node:internal/timers:514:7) { Feb 10 18:34:54 volumio volumio[1039]: code: 'ERR_INTERNAL_ASSERTION' Feb 10 18:34:54 volumio volumio[1039]: } Feb 10 18:34:54 volumio volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 18:35:00 volumio sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 18:33' Feb 10 18:35:00 volumio sudo[1775]: 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"