Sep 07 10:03:02 volumio-bookworm systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 07 10:03:05 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:10 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 10:03:10 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 10:03:10 volumio-bookworm volumio[1428]: info: Discovery: Getting this device information Sep 07 10:03:10 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:10 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 10:03:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 10:03:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 10:03:13 volumio-bookworm volumio[1428]: info: Discovery: Getting this device information Sep 07 10:03:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 10:03:15 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:15 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:03:15 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:03:17 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 10:03:17 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 07 10:03:21 volumio-bookworm volumio[1428]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 10:03:21 volumio-bookworm volumio[1428]: info: Loaded the previous config. Sep 07 10:03:21 volumio-bookworm volumio[1428]: info: ## populating UI... Sep 07 10:03:21 volumio-bookworm volumio[1428]: info: 1/1 settings loaded Sep 07 10:03:21 volumio-bookworm volumio[1428]: info: Populated config screen. Sep 07 10:03:25 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:35 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:35 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:03:35 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:03:43 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 10:03:43 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 10:03:43 volumio-bookworm volumio[1428]: info: Discovery: Getting this device information Sep 07 10:03:43 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:43 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 10:03:45 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:55 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:03:55 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:03:55 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:04:03 volumio-bookworm volumio[1428]: info: CALLMETHOD: system_hardware audiophonicsonoff updateButtonConfig [object Object] Sep 07 10:04:03 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Sep 07 10:04:05 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:04:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 10:04:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 10:04:13 volumio-bookworm volumio[1428]: info: Discovery: Getting this device information Sep 07 10:04:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:04:13 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 10:04:15 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:04:15 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:04:15 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:04:25 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:04:27 volumio-bookworm volumio[1428]: info: CALLMETHOD: system_hardware audiophonicsonoff updateButtonConfig [object Object] Sep 07 10:04:27 volumio-bookworm volumio[1428]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Sep 07 10:04:32 volumio-bookworm systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 07 10:04:32 volumio-bookworm dbus-daemon[690]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid=0 pid=2382 comm="timedatectl show --property=NTPSynchronized --valu") Sep 07 10:04:32 volumio-bookworm systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 07 10:04:32 volumio-bookworm dbus-daemon[690]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 07 10:04:32 volumio-bookworm systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 07 10:04:32 volumio-bookworm setdatetime-helper.sh[2381]: Time is not synchronized. Attempting to sync... Sep 07 10:04:32 volumio-bookworm sudo[2390]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 07 Sep 2025 08:04:33 GMT#015' Sep 07 10:04:32 volumio-bookworm sudo[2390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 07 10:04:33 volumio-bookworm setdatetime-helper.sh[2391]: Sun Sep 7 10:04:33 CEST 2025 Sep 07 10:04:33 volumio-bookworm sudo[2390]: pam_unix(sudo:session): session closed for user root Sep 07 10:04:33 volumio-bookworm setdatetime-helper.sh[2381]: Time synchronized successfully. Sep 07 10:04:33 volumio-bookworm systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 07 10:04:33 volumio-bookworm systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 07 10:04:35 volumio-bookworm volumio[1428]: info: CoreCommandRouter::volumioGetState Sep 07 10:04:35 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:04:35 volumio-bookworm volumio[1428]: info: Listing playlists Sep 07 10:04:43 volumio-bookworm volumio[1428]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Sep 07 10:04:43 volumio-bookworm volumio[1428]: info: PLUGIN onShutdown : networkfs Sep 07 10:04:43 volumio-bookworm volumio[1428]: info: PLUGIN onShutdown : audiophonicsonoff Sep 07 10:04:43 volumio-bookworm volumio[1428]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 10:04:43 volumio-bookworm volumio[1428]: TypeError: Cannot read properties of undefined (reading 'writeSync') Sep 07 10:04:43 volumio-bookworm volumio[1428]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at Socket.emit (node:events:514:28) Sep 07 10:04:43 volumio-bookworm volumio[1428]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 07 10:04:43 volumio-bookworm volumio[1428]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Sep 07 10:04:43 volumio-bookworm volumio[1428]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 10:04:44 volumio-bookworm sudo[2421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-07 10:03' Sep 07 10:04:44 volumio-bookworm sudo[2421]: 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="477d19cd2b4ebf4b4c802be78e00bbb657e83aea" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 2 17:34:53 UTC 2025" VOLUMIO_VERSION="4.021" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6199706cbb86ad1289fc7190b8a00601"