Dec 12 19:49:00 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:49:01 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 19:49:01 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 19:49:03 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:49:03 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:49:03 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:49:06 volumio-socrates volumio[11492]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/bookworm/armhf Dec 12 19:49:07 volumio-socrates volumio[11492]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/bookworm/armhf Dec 12 19:49:07 volumio-socrates volumio[11492]: info: Folder /tmp/plugins removed Dec 12 19:49:07 volumio-socrates volumio[11492]: info: Check plugin dependencies Dec 12 19:49:07 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 19:49:07 volumio-socrates volumio[11492]: info: Checking if plugin already exists Dec 12 19:49:07 volumio-socrates volumio[11492]: info: Rename folder Dec 12 19:49:07 volumio-socrates volumio[11492]: info: Folder /tmp/downloaded_plugin.zip removed Dec 12 19:49:07 volumio-socrates volumio[11492]: info: Move to category Dec 12 19:49:08 volumio-socrates volumio[11492]: info: Checking if install.sh is present Dec 12 19:49:08 volumio-socrates volumio[11492]: info: Executing install.sh Dec 12 19:49:08 volumio-socrates sudo[12420]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/Systeminfo/install.sh Dec 12 19:49:08 volumio-socrates sudo[12420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:49:08 volumio-socrates volumio[11492]: info: Installing systeminfo Dec 12 19:49:08 volumio-socrates sudo[12422]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Dec 12 19:49:08 volumio-socrates sudo[12422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 19:49:08 volumio-socrates volumio[11492]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Dec 12 19:49:08 volumio-socrates volumio[11492]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Dec 12 19:49:08 volumio-socrates volumio[11492]: info: Hit:3 https://deb.nodesource.com/node_20.x nodistro InRelease Dec 12 19:49:13 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:49:16 volumio-socrates volumio[11492]: info: Reading package lists... Dec 12 19:49:17 volumio-socrates volumio[11492]: info: Building dependency tree... Dec 12 19:49:18 volumio-socrates volumio[11492]: info: Reading state information... Dec 12 19:49:18 volumio-socrates sudo[12422]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:18 volumio-socrates volumio[11492]: info: 2 packages can be upgraded. Run 'apt list --upgradable' to see them. Dec 12 19:49:18 volumio-socrates sudo[12644]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt install -y sysbench Dec 12 19:49:18 volumio-socrates sudo[12644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 19:49:19 volumio-socrates volumio[11492]: info: Reading package lists... Dec 12 19:49:19 volumio-socrates go-librespot[11767]: time="2025-12-12T19:49:19+01:00" level=trace msg="received accesspoint ping" Dec 12 19:49:19 volumio-socrates go-librespot[11767]: time="2025-12-12T19:49:19+01:00" level=trace msg="received accesspoint pong ack" Dec 12 19:49:19 volumio-socrates go-librespot[11767]: time="2025-12-12T19:49:19+01:00" level=trace msg="sent dealer ping" Dec 12 19:49:19 volumio-socrates go-librespot[11767]: time="2025-12-12T19:49:19+01:00" level=trace msg="received dealer pong" Dec 12 19:49:20 volumio-socrates volumio[11492]: info: Building dependency tree... Dec 12 19:49:20 volumio-socrates volumio[11492]: info: Reading state information... Dec 12 19:49:21 volumio-socrates volumio[11492]: info: The following additional packages will be installed: Dec 12 19:49:21 volumio-socrates volumio[11492]: info: libaio1 libluajit2-5.1-2 libluajit2-5.1-common libmariadb3 libpq5 Dec 12 19:49:21 volumio-socrates volumio[11492]: info: mariadb-common mysql-common Dec 12 19:49:21 volumio-socrates volumio[11492]: info: The following NEW packages will be installed: Dec 12 19:49:21 volumio-socrates volumio[11492]: info: libaio1 libluajit2-5.1-2 libluajit2-5.1-common libmariadb3 libpq5 Dec 12 19:49:21 volumio-socrates volumio[11492]: info: mariadb-common mysql-common sysbench Dec 12 19:49:22 volumio-socrates volumio[11492]: info: 0 upgraded, 8 newly installed, 0 to remove and 2 not upgraded. Dec 12 19:49:22 volumio-socrates volumio[11492]: info: Need to get 727 kB of archives. Dec 12 19:49:22 volumio-socrates volumio[11492]: info: After this operation, 2609 kB of additional disk space will be used. Dec 12 19:49:22 volumio-socrates volumio[11492]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libaio1 armhf 0.3.113-4+rpi1 [13.3 kB] Dec 12 19:49:22 volumio-socrates volumio[11492]: info: Get:2 http://mirror.nl.leaseweb.net/raspbian/raspbian bookworm/main armhf libluajit2-5.1-common all 2.1-20230119-1 [46.6 kB] Dec 12 19:49:22 volumio-socrates volumio[11492]: info: Get:3 http://mirror.nl.leaseweb.net/raspbian/raspbian bookworm/main armhf libluajit2-5.1-2 armhf 2.1-20230119-1 [211 kB] Dec 12 19:49:22 volumio-socrates volumio[11492]: info: Get:5 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mariadb-common all 1:10.11.3-1+rpi1 [24.1 kB] Dec 12 19:49:23 volumio-socrates volumio[11492]: info: Get:4 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mysql-common all 5.8+1.1.0 [6636 B] Dec 12 19:49:23 volumio-socrates volumio[11492]: info: Get:6 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libmariadb3 armhf 1:10.11.3-1+rpi1 [154 kB] Dec 12 19:49:23 volumio-socrates volumio[11492]: info: Get:7 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libpq5 armhf 15.14-0+deb12u1 [166 kB] Dec 12 19:49:23 volumio-socrates volumio[11492]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf sysbench armhf 1.0.20+ds-5 [104 kB] Dec 12 19:49:23 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:49:23 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:49:23 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:49:24 volumio-socrates systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 12 19:49:25 volumio-socrates volumio[11492]: info: Fetched 727 kB in 1s (526 kB/s) Dec 12 19:49:25 volumio-socrates volumio[11492]: info: Selecting previously unselected package libaio1:armhf. Dec 12 19:49:26 volumio-socrates systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 12 19:49:26 volumio-socrates systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 12 19:49:26 volumio-socrates systemd[1]: setdatetime-helper.service: Consumed 1.384s CPU time. Dec 12 19:49:27 volumio-socrates volumio[11492]: [620B blob data] Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Preparing to unpack .../0-libaio1_0.3.113-4+rpi1_armhf.deb ... Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Unpacking libaio1:armhf (0.3.113-4+rpi1) ... Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Selecting previously unselected package libluajit2-5.1-common. Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Preparing to unpack .../1-libluajit2-5.1-common_2.1-20230119-1_all.deb ... Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Unpacking libluajit2-5.1-common (2.1-20230119-1) ... Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Selecting previously unselected package libluajit2-5.1-2:armhf. Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Preparing to unpack .../2-libluajit2-5.1-2_2.1-20230119-1_armhf.deb ... Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Unpacking libluajit2-5.1-2:armhf (2.1-20230119-1) ... Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Selecting previously unselected package mysql-common. Dec 12 19:49:27 volumio-socrates volumio[11492]: info: Preparing to unpack .../3-mysql-common_5.8+1.1.0_all.deb ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Unpacking mysql-common (5.8+1.1.0) ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Selecting previously unselected package mariadb-common. Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Preparing to unpack .../4-mariadb-common_1%3a10.11.3-1+rpi1_all.deb ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Unpacking mariadb-common (1:10.11.3-1+rpi1) ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Selecting previously unselected package libmariadb3:armhf. Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Preparing to unpack .../5-libmariadb3_1%3a10.11.3-1+rpi1_armhf.deb ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Unpacking libmariadb3:armhf (1:10.11.3-1+rpi1) ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Selecting previously unselected package libpq5:armhf. Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Preparing to unpack .../6-libpq5_15.14-0+deb12u1_armhf.deb ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Unpacking libpq5:armhf (15.14-0+deb12u1) ... Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Selecting previously unselected package sysbench. Dec 12 19:49:28 volumio-socrates volumio[11492]: info: Preparing to unpack .../7-sysbench_1.0.20+ds-5_armhf.deb ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Unpacking sysbench (1.0.20+ds-5) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up mysql-common (5.8+1.1.0) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: update-alternatives: using /etc/mysql/my.cnf.fallback to provide /etc/mysql/my.cnf (my.cnf) in auto mode Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up libpq5:armhf (15.14-0+deb12u1) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up libluajit2-5.1-common (2.1-20230119-1) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up mariadb-common (1:10.11.3-1+rpi1) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: update-alternatives: using /etc/mysql/mariadb.cnf to provide /etc/mysql/my.cnf (my.cnf) in auto mode Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up libmariadb3:armhf (1:10.11.3-1+rpi1) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up libaio1:armhf (0.3.113-4+rpi1) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up libluajit2-5.1-2:armhf (2.1-20230119-1) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Setting up sysbench (1.0.20+ds-5) ... Dec 12 19:49:29 volumio-socrates volumio[11492]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 12 19:49:31 volumio-socrates sudo[12644]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Configuration File exists removing it Dec 12 19:49:31 volumio-socrates sudo[12863]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm /data/configuration/user_interface/Systeminfo/config.json Dec 12 19:49:31 volumio-socrates sudo[12863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 19:49:31 volumio-socrates sudo[12863]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Detected cpu architecture as armv7l Dec 12 19:49:31 volumio-socrates sudo[12868]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/user_interface/Systeminfo/c/hw_params_arm /data/plugins/user_interface/Systeminfo/hw_params Dec 12 19:49:31 volumio-socrates sudo[12868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 19:49:31 volumio-socrates sudo[12868]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:31 volumio-socrates sudo[12870]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/Systeminfo/hw_params Dec 12 19:49:31 volumio-socrates sudo[12870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 19:49:31 volumio-socrates sudo[12870]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:31 volumio-socrates sudo[12420]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:31 volumio-socrates volumio[11492]: Plugin install end detected on script Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Install script completed Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Adding reference to registry Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Done installing plugin. Dec 12 19:49:31 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 19:49:31 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Folder /tmp/plugins removed Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Folder /tmp/downloaded_plugin.zip removed Dec 12 19:49:31 volumio-socrates volumio[11492]: info: Folder /data/temp removed Dec 12 19:49:33 volumio-socrates volumio[11492]: info: Enabling plugin Systeminfo Dec 12 19:49:33 volumio-socrates volumio[11492]: info: Loading plugin "Systeminfo"... Dec 12 19:49:34 volumio-socrates volumio[11492]: info: PLUGIN START: Systeminfo Dec 12 19:49:34 volumio-socrates volumio[11492]: info: Done. Dec 12 19:49:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:49:35 volumio-socrates volumio[11492]: info: Enabling plugin Systeminfo Dec 12 19:49:35 volumio-socrates volumio[11492]: info: Loading plugin "Systeminfo"... Dec 12 19:49:35 volumio-socrates volumio[11492]: info: PLUGIN START: Systeminfo Dec 12 19:49:35 volumio-socrates volumio[11492]: info: Done. Dec 12 19:49:38 volumio-socrates volumio[11492]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 19:49:40 volumio-socrates volumio[11492]: info: CALLMETHOD: user_interface Systeminfo getsysteminfo undefined Dec 12 19:49:40 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , getsysteminfo Dec 12 19:49:40 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 19:49:40 volumio-socrates dbus-daemon[715]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.42' (uid=1000 pid=12948 comm="hostnamectl --json short") Dec 12 19:49:40 volumio-socrates systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Dec 12 19:49:41 volumio-socrates dbus-daemon[715]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 12 19:49:41 volumio-socrates systemd[1]: Started systemd-hostnamed.service - Hostname Service. Dec 12 19:49:41 volumio-socrates dbus-daemon[715]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.43' (uid=0 pid=12949 comm="/lib/systemd/systemd-hostnamed") Dec 12 19:49:41 volumio-socrates systemd[1]: Starting polkit.service - Authorization Manager... Dec 12 19:49:41 volumio-socrates polkitd[12951]: Started polkitd version 122 Dec 12 19:49:41 volumio-socrates polkitd[12951]: Loading rules from directory /etc/polkit-1/rules.d Dec 12 19:49:41 volumio-socrates polkitd[12951]: Loading rules from directory /usr/share/polkit-1/rules.d Dec 12 19:49:41 volumio-socrates polkitd[12951]: Finished loading, compiling and executing 2 rules Dec 12 19:49:41 volumio-socrates dbus-daemon[715]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 12 19:49:41 volumio-socrates systemd[1]: Started polkit.service - Authorization Manager. Dec 12 19:49:41 volumio-socrates polkitd[12951]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Dec 12 19:49:41 volumio-socrates dbus-daemon[715]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.43" (uid=0 pid=12949 comm="/lib/systemd/systemd-hostnamed") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.InteractiveAuthorizationRequired" requested_reply="0" destination=":1.42" (uid=1000 pid=12948 comm="hostnamectl --json short") Dec 12 19:49:43 volumio-socrates volumio[11492]: Upnp client error: Error: This socket has been ended by the other party Dec 12 19:49:43 volumio-socrates sudo[13150]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/vcgencmd version Dec 12 19:49:43 volumio-socrates sudo[13150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:49:43 volumio-socrates sudo[13150]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:49:44 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:49:44 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:49:48 volumio-socrates sudo[13652]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 12 19:49:48 volumio-socrates sudo[13652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:49:48 volumio-socrates sudo[13652]: pam_unix(sudo:session): session closed for user root Dec 12 19:49:49 volumio-socrates go-librespot[11767]: time="2025-12-12T19:49:49+01:00" level=trace msg="sent dealer ping" Dec 12 19:49:49 volumio-socrates go-librespot[11767]: time="2025-12-12T19:49:49+01:00" level=trace msg="received dealer pong" Dec 12 19:49:53 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:03 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:03 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:03 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:11 volumio-socrates volumio[11492]: info: CoreCommandRouter::Close All Modals sent Dec 12 19:50:11 volumio-socrates systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 12 19:50:13 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:13 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:14 volumio-socrates volumio[11492]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 19:50:14 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 19:50:15 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 19:50:15 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 19:50:15 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 12 19:50:15 volumio-socrates volumio[11492]: info: Received Get System Version Dec 12 19:50:15 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 19:50:19 volumio-socrates go-librespot[11767]: time="2025-12-12T19:50:19+01:00" level=trace msg="sent dealer ping" Dec 12 19:50:19 volumio-socrates go-librespot[11767]: time="2025-12-12T19:50:19+01:00" level=trace msg="received dealer pong" Dec 12 19:50:22 volumio-socrates volumio[11492]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.141 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetQueue Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreStateMachine::getQueue Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CorePlayQueue::getQueue Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Dec 12 19:50:22 volumio-socrates volumio[11492]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Dec 12 19:50:22 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Dec 12 19:50:23 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:23 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:23 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:24 volumio-socrates volumio[11492]: info: CALLMETHOD: system_controller system enableSSH true Dec 12 19:50:24 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Dec 12 19:50:24 volumio-socrates sudo[13848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Dec 12 19:50:24 volumio-socrates sudo[13848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:24 volumio-socrates sudo[13848]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:24 volumio-socrates sudo[13850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Dec 12 19:50:24 volumio-socrates sudo[13850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:24 volumio-socrates systemd[1]: Reloading. Dec 12 19:50:25 volumio-socrates systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 19:50:25 volumio-socrates systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 19:50:26 volumio-socrates systemd[1]: Reloading. Dec 12 19:50:27 volumio-socrates systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 19:50:27 volumio-socrates systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 19:50:27 volumio-socrates systemd[1]: Reloading. Dec 12 19:50:28 volumio-socrates systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 19:50:28 volumio-socrates systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 19:50:28 volumio-socrates sudo[13850]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:28 volumio-socrates volumio[11492]: info: enable SSH service success Dec 12 19:50:33 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:33 volumio-socrates sudo[13959]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 12 19:50:33 volumio-socrates sudo[13959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:33 volumio-socrates sudo[13959]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:33 volumio-socrates sudo[13961]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 12 19:50:33 volumio-socrates sudo[13961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:33 volumio-socrates sudo[13961]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:33 volumio-socrates volumio[11492]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.141 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 12 19:50:33 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 19:50:34 volumio-socrates volumio[11492]: info: Received Get System Info Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:50:34 volumio-socrates volumio[11492]: info: Discovery: Getting this device information Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:34 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 12 19:50:34 volumio-socrates volumio[11492]: info: Received Get System Version Dec 12 19:50:34 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 19:50:35 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 12 19:50:35 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 19:50:35 volumio-socrates volumio[11492]: info: Received Get System Info Dec 12 19:50:35 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:50:35 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:50:35 volumio-socrates volumio[11492]: info: Discovery: Getting this device information Dec 12 19:50:35 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:35 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 19:50:36 volumio-socrates volumio[11492]: info: Received Get System Info Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:50:36 volumio-socrates volumio[11492]: info: Discovery: Getting this device information Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Dec 12 19:50:36 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 12 19:50:43 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:43 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:43 volumio-socrates volumio[11492]: info: Listing playlists Dec 12 19:50:43 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 12 19:50:44 volumio-socrates volumio[11492]: info: System name has changed, restarting Shairport Sync Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:44 volumio-socrates volumio[11492]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 19:50:44 volumio-socrates volumio[11492]: info: Updating MyVolumio device info Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:44 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:44 volumio-socrates volumio[11492]: info: Discovery: Restarting Advertising due to device name change Dec 12 19:50:44 volumio-socrates sudo[14033]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 12 19:50:44 volumio-socrates sudo[14033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:44 volumio-socrates sudo[14033]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:44 volumio-socrates volumio[11492]: info: Starting Shairport Sync Dec 12 19:50:44 volumio-socrates sudo[14036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 12 19:50:44 volumio-socrates sudo[14036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:44 volumio-socrates sudo[14036]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:44 volumio-socrates volumio[11492]: info: Permissions for /etc/hosts set Dec 12 19:50:44 volumio-socrates sudo[14038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 19:50:44 volumio-socrates sudo[14038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:44 volumio-socrates volumio[11492]: info: Hostname now is volumio-socratesv Dec 12 19:50:44 volumio-socrates systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 19:50:44 volumio-socrates systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 19:50:44 volumio-socrates sudo[14041]: volumio : unable to resolve host volumio-socrates: System error Dec 12 19:50:44 volumio-socrates systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 19:50:44 volumio-socrates systemd[1]: shairport-sync.service: Consumed 2.992s CPU time. Dec 12 19:50:44 volumio-socrates sudo[14041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-socratesv Dec 12 19:50:44 volumio-socrates sudo[14041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:44 volumio-socratesv sudo[14041]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:44 volumio-socratesv sudo[14044]: volumio : unable to resolve host volumio-socrates: System error Dec 12 19:50:44 volumio-socratesv volumio[11492]: info: New hostname set Dec 12 19:50:44 volumio-socratesv sudo[14044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 12 19:50:44 volumio-socratesv sudo[14044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:44 volumio-socratesv sudo[14044]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:44 volumio-socratesv volumio[11492]: info: Permissions for /etc/avahi/services/volumio.service Dec 12 19:50:44 volumio-socratesv volumio[11492]: info: Avahi name changed to volumio-socratesv Dec 12 19:50:44 volumio-socratesv avahi-daemon[714]: Files changed, reloading. Dec 12 19:50:44 volumio-socratesv systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 19:50:44 volumio-socratesv avahi-daemon[714]: Service group file /services/volumio.service changed, reloading. Dec 12 19:50:44 volumio-socratesv sudo[14038]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:44 volumio-socratesv volumio[11492]: info: Shairport-Sync Started Dec 12 19:50:44 volumio-socratesv volumio[11492]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 12 19:50:44 volumio-socratesv volumio[11492]: Upnp client error: Error: read ECONNRESET Dec 12 19:50:44 volumio-socratesv volumio[11492]: Upnp client error: Error: read ECONNRESET Dec 12 19:50:44 volumio-socratesv volumio[11492]: Upnp client error: Error: read ECONNRESET Dec 12 19:50:44 volumio-socratesv systemd[1]: upmpdcli.service: Deactivated successfully. Dec 12 19:50:44 volumio-socratesv systemd[1]: upmpdcli.service: Consumed 6.885s CPU time. Dec 12 19:50:45 volumio-socratesv avahi-daemon[714]: Service "volumio-socratesv" (/services/volumio.service) successfully established. Dec 12 19:50:45 volumio-socratesv volumio[11492]: info: Discovery: A device disappeared from network Dec 12 19:50:45 volumio-socratesv volumio[11492]: info: Discovery: A device disappeared from network Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Discovery: Started advertising with name: volumio-socratesv Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: System name has changed, restarting Shairport Sync Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Updating MyVolumio device info Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Discovery: Restarting Advertising due to device name change Dec 12 19:50:49 volumio-socratesv sudo[14086]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 12 19:50:49 volumio-socratesv sudo[14086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Starting Shairport Sync Dec 12 19:50:49 volumio-socratesv sudo[14086]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:49 volumio-socratesv sudo[14089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 12 19:50:49 volumio-socratesv sudo[14089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:49 volumio-socratesv volumio[11492]: error: Cannot kill upmpdcli Error: Command failed: /usr/bin/sudo /usr/bin/killall upmpdcli Dec 12 19:50:49 volumio-socratesv volumio[11492]: upmpdcli: no process found Dec 12 19:50:49 volumio-socratesv sudo[14089]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Permissions for /etc/hosts set Dec 12 19:50:49 volumio-socratesv sudo[14092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 19:50:49 volumio-socratesv volumio[11492]: info: Hostname now is volumio-socrates Dec 12 19:50:49 volumio-socratesv sudo[14092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:49 volumio-socratesv systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 19:50:49 volumio-socratesv systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 19:50:49 volumio-socratesv systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 19:50:49 volumio-socratesv systemd[1]: shairport-sync.service: Consumed 1.605s CPU time. Dec 12 19:50:49 volumio-socratesv sudo[14094]: volumio : unable to resolve host volumio-socratesv: System error Dec 12 19:50:49 volumio-socratesv sudo[14094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-socrates Dec 12 19:50:49 volumio-socratesv sudo[14094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:49 volumio-socrates sudo[14094]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:49 volumio-socrates volumio[11492]: info: New hostname set Dec 12 19:50:49 volumio-socrates sudo[14097]: volumio : unable to resolve host volumio-socratesv: System error Dec 12 19:50:49 volumio-socrates sudo[14097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 12 19:50:49 volumio-socrates sudo[14097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:49 volumio-socrates sudo[14097]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:49 volumio-socrates volumio[11492]: info: Permissions for /etc/avahi/services/volumio.service Dec 12 19:50:49 volumio-socrates avahi-daemon[714]: Files changed, reloading. Dec 12 19:50:49 volumio-socrates volumio[11492]: info: Avahi name changed to volumio-socrates Dec 12 19:50:49 volumio-socrates avahi-daemon[714]: Service group file /services/volumio.service changed, reloading. Dec 12 19:50:49 volumio-socrates systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 19:50:49 volumio-socrates sudo[14092]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:49 volumio-socrates volumio[11492]: info: Shairport-Sync Started Dec 12 19:50:49 volumio-socrates volumio[11492]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 12 19:50:49 volumio-socrates go-librespot[11767]: time="2025-12-12T19:50:49+01:00" level=trace msg="sent dealer ping" Dec 12 19:50:49 volumio-socrates go-librespot[11767]: time="2025-12-12T19:50:49+01:00" level=trace msg="received dealer pong" Dec 12 19:50:50 volumio-socrates avahi-daemon[714]: Service "volumio-socrates" (/services/volumio.service) successfully established. Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 12 19:50:51 volumio-socrates volumio[11492]: info: Received Get System Version Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 19:50:51 volumio-socrates volumio[11492]: info: Received Get System Info Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 19:50:51 volumio-socrates volumio[11492]: info: Discovery: Getting this device information Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:51 volumio-socrates volumio[11492]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 19:50:53 volumio-socrates volumio[11492]: info: CoreCommandRouter::volumioGetState Dec 12 19:50:54 volumio-socrates sudo[14116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Dec 12 19:50:54 volumio-socrates sudo[14116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates sudo[14116]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:54 volumio-socrates sudo[14118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Dec 12 19:50:54 volumio-socrates sudo[14118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates sudo[14118]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:54 volumio-socrates volumio[11492]: info: Internal perms successfully set Dec 12 19:50:54 volumio-socrates volumio[11492]: info: smb.conf Permissions set Dec 12 19:50:54 volumio-socrates sudo[14122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Dec 12 19:50:54 volumio-socrates sudo[14122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates volumio[11492]: info: Discovery: Started advertising with name: volumio-socrates Dec 12 19:50:54 volumio-socrates sudo[14124]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 12 19:50:54 volumio-socrates sudo[14124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates sudo[14124]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:54 volumio-socrates sudo[14126]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 12 19:50:54 volumio-socrates sudo[14126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates sudo[14126]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:54 volumio-socrates systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Dec 12 19:50:54 volumio-socrates nmbd[2397]: [2025/12/12 19:50:54.277267, 0] ../../source3/nmbd/nmbd.c:59(terminate) Dec 12 19:50:54 volumio-socrates nmbd[2397]: Got SIGTERM: going down... Dec 12 19:50:54 volumio-socrates systemd[1]: nmbd.service: Deactivated successfully. Dec 12 19:50:54 volumio-socrates systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Dec 12 19:50:54 volumio-socrates systemd[1]: nmbd.service: Consumed 1.352s CPU time. Dec 12 19:50:54 volumio-socrates sudo[14133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 12 19:50:54 volumio-socrates sudo[14133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates systemd[1]: Starting nmbd.service - Samba NMB Daemon... Dec 12 19:50:54 volumio-socrates nmbd[14138]: [2025/12/12 19:50:54.859163, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 12 19:50:54 volumio-socrates nmbd[14138]: nmbd version 4.17.8-Raspbian started. Dec 12 19:50:54 volumio-socrates nmbd[14138]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 12 19:50:54 volumio-socrates nmbd[14138]: [2025/12/12 19:50:54.864238, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 12 19:50:54 volumio-socrates nmbd[14138]: started asyncdns process 14139 Dec 12 19:50:54 volumio-socrates systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 12 19:50:54 volumio-socrates sudo[14122]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:54 volumio-socrates nmbd[14138]: [2025/12/12 19:50:54.891337, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 12 19:50:54 volumio-socrates nmbd[14138]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.120 for name WORKGROUP<1d>. Dec 12 19:50:54 volumio-socrates nmbd[14138]: This response was from IP 192.168.1.124, reporting an IP address of 192.168.1.124. Dec 12 19:50:54 volumio-socrates sudo[14141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Dec 12 19:50:54 volumio-socrates sudo[14141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 19:50:54 volumio-socrates systemd[1]: Stopping smbd.service - Samba SMB Daemon... Dec 12 19:50:54 volumio-socrates systemd[1]: smbd.service: Deactivated successfully. Dec 12 19:50:54 volumio-socrates systemd[1]: Stopped smbd.service - Samba SMB Daemon. Dec 12 19:50:54 volumio-socrates systemd[1]: smbd.service: Consumed 7.101s CPU time. Dec 12 19:50:55 volumio-socrates systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 12 19:50:55 volumio-socrates volumio[11492]: compat.c: read() failed: Resource temporarily unavailable Dec 12 19:50:55 volumio-socrates volumio[11492]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 19:50:55 volumio-socrates volumio[11492]: Error: dns service error: unknown Dec 12 19:50:55 volumio-socrates volumio[11492]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 12 19:50:55 volumio-socrates volumio[11492]: errorCode: -65537 Dec 12 19:50:55 volumio-socrates volumio[11492]: } Dec 12 19:50:55 volumio-socrates volumio[11492]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 19:50:55 volumio-socrates smbd[14159]: [2025/12/12 19:50:55.548526, 0] ../../source3/smbd/server.c:1741(main) Dec 12 19:50:55 volumio-socrates smbd[14159]: smbd version 4.17.8-Raspbian started. Dec 12 19:50:55 volumio-socrates smbd[14159]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 12 19:50:55 volumio-socrates systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 12 19:50:55 volumio-socrates sudo[14141]: pam_unix(sudo:session): session closed for user root Dec 12 19:50:55 volumio-socrates sudo[14165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 19:49' Dec 12 19:50:55 volumio-socrates sudo[14165]: 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"