-- Logs begin at Thu 2024-10-24 09:08:43 CEST, end at Thu 2024-10-24 10:07:52 CEST. -- Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.3321054a does not match aorg 0000000000.00000000 from server@95.81.173.74 xmt 0xeac47eaa.76debdf2 Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.3325c9b4 does not match aorg 0000000000.00000000 from server@51.68.44.27 xmt 0xeac47eaa.782dd7fe Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.331fa4d8 does not match aorg 0000000000.00000000 from server@51.178.79.86 xmt 0xeac47eaa.781f9f7a Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.3322ff87 does not match aorg 0000000000.00000000 from server@185.123.84.51 xmt 0xeac47eaa.77ca1b75 Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.3321f1b5 does not match aorg 0000000000.00000000 from server@82.65.248.56 xmt 0xeac47eaa.7632c3ee Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.3324e5ba does not match aorg 0000000000.00000000 from server@129.151.225.244 xmt 0xeac47eaa.78e3b92c Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.331ce8a2 does not match aorg 0000000000.00000000 from server@51.38.99.44 xmt 0xeac47eaa.78aa18b0 Oct 24 10:07:06 vmcdac ntpd[781]: receive: Unexpected origin timestamp 0xeac4714a.3323e795 does not match aorg 0000000000.00000000 from server@51.195.104.188 xmt 0xeac47eaa.795a1ac2 Oct 24 10:07:07 vmcdac volumio[1047]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/audiophonicsonoff/volumio/buster/armhf Oct 24 10:07:07 vmcdac volumio[1047]: info: Folder /tmp/plugins removed Oct 24 10:07:07 vmcdac volumio[1047]: info: Check plugin dependencies Oct 24 10:07:07 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 10:07:07 vmcdac volumio[1047]: info: Checking if plugin already exists Oct 24 10:07:07 vmcdac volumio[1047]: info: Rename folder Oct 24 10:07:07 vmcdac volumio[1047]: info: Folder /tmp/downloaded_plugin.zip removed Oct 24 10:07:07 vmcdac volumio[1047]: info: Move to category Oct 24 10:07:07 vmcdac volumio[1047]: info: Checking if install.sh is present Oct 24 10:07:07 vmcdac volumio[1047]: info: Executing install.sh Oct 24 10:07:07 vmcdac sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/audiophonicsonoff/install.sh Oct 24 10:07:07 vmcdac sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 10:07:07 vmcdac volumio[1047]: info: Installing Audiophonics on/off and its dependencies... Oct 24 10:07:07 vmcdac sudo[1574]: pam_unix(sudo:session): session closed for user root Oct 24 10:07:07 vmcdac volumio[1047]: info: Install script completed Oct 24 10:07:07 vmcdac volumio[1047]: info: Adding reference to registry Oct 24 10:07:07 vmcdac volumio[1047]: info: Done installing plugin. Oct 24 10:07:07 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 10:07:07 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 24 10:07:07 vmcdac volumio[1047]: info: No packages needed, only node_modules are required Oct 24 10:07:07 vmcdac volumio[1047]: Plugin install end detected on script Oct 24 10:07:07 vmcdac volumio[1047]: info: Folder /tmp/plugins removed Oct 24 10:07:07 vmcdac volumio[1047]: info: Folder /tmp/downloaded_plugin.zip removed Oct 24 10:07:07 vmcdac volumio[1047]: info: Folder /data/temp removed Oct 24 10:07:09 vmcdac volumio[1047]: info: Enabling plugin audiophonicsonoff Oct 24 10:07:09 vmcdac volumio[1047]: info: Loading plugin "audiophonicsonoff"... Oct 24 10:07:09 vmcdac volumio[1047]: info: Audiophonics on/off initiated Oct 24 10:07:09 vmcdac volumio[1047]: info: PLUGIN START: audiophonicsonoff Oct 24 10:07:09 vmcdac volumio[1047]: info: Configuring GPIO pins Oct 24 10:07:09 vmcdac volumio[1047]: info: Error: Error: EBUSY: resource busy or locked, write Oct 24 10:07:18 vmcdac volumio[1047]: info: Enabling plugin audiophonicsonoff Oct 24 10:07:18 vmcdac volumio[1047]: info: Loading plugin "audiophonicsonoff"... Oct 24 10:07:18 vmcdac volumio[1047]: info: Applying required configuration parameters for plugin audiophonicsonoff Oct 24 10:07:18 vmcdac volumio[1047]: info: Audiophonics on/off initiated Oct 24 10:07:18 vmcdac volumio[1047]: info: PLUGIN START: audiophonicsonoff Oct 24 10:07:18 vmcdac volumio[1047]: info: Configuring GPIO pins Oct 24 10:07:18 vmcdac volumio[1047]: info: Error: Error: EBUSY: resource busy or locked, write Oct 24 10:07:22 vmcdac volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 10:07:22 vmcdac volumio[1047]: info: Loaded the previous config. Oct 24 10:07:22 vmcdac volumio[1047]: info: ## populating UI... Oct 24 10:07:22 vmcdac volumio[1047]: info: 1/1 settings loaded Oct 24 10:07:22 vmcdac volumio[1047]: info: Populated config screen. Oct 24 10:07:33 vmcdac volumio[1047]: info: CALLMETHOD: system_controller audiophonicsonoff updateButtonConfig [object Object] Oct 24 10:07:33 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Oct 24 10:07:39 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 10:07:39 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 24 10:07:45 vmcdac volumio[1047]: info: CoreCommandRouter::volumioGetState Oct 24 10:07:45 vmcdac volumio[1047]: info: CorePlayQueue::getTrack 0 Oct 24 10:07:47 vmcdac volumio[1047]: info: Executing endpoint metavolumio Oct 24 10:07:47 vmcdac volumio[1047]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 10:07:51 vmcdac volumio[1047]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Oct 24 10:07:51 vmcdac volumio[1047]: info: PLUGIN onShutdown : networkfs Oct 24 10:07:51 vmcdac volumio[1047]: info: PLUGIN onShutdown : audiophonicsonoff Oct 24 10:07:51 vmcdac volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 10:07:51 vmcdac volumio[1047]: TypeError: Cannot read property 'writeSync' of undefined Oct 24 10:07:51 vmcdac volumio[1047]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Oct 24 10:07:51 vmcdac volumio[1047]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Oct 24 10:07:51 vmcdac volumio[1047]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Oct 24 10:07:51 vmcdac volumio[1047]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 24 10:07:51 vmcdac volumio[1047]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 24 10:07:51 vmcdac volumio[1047]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Oct 24 10:07:51 vmcdac volumio[1047]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1319:24) Oct 24 10:07:51 vmcdac volumio[1047]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Oct 24 10:07:51 vmcdac volumio[1047]: at Socket.emit (events.js:315:20) Oct 24 10:07:51 vmcdac volumio[1047]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 24 10:07:51 vmcdac volumio[1047]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 24 10:07:51 vmcdac volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 10:07:52 vmcdac sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 10:06 Oct 24 10:07:52 vmcdac sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"