-- Logs begin at Tue 2025-02-18 17:17:59 UTC, end at Tue 2025-02-18 18:33:41 UTC. -- Feb 18 18:32:03 volumio volumio[820]: info: CALLMETHOD: system_controller system setTestPlugins true Feb 18 18:32:03 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Feb 18 18:32:03 volumio volumio[820]: info: Plugins store is now in test mode Feb 18 18:32:11 volumio volumio[820]: info: CALLMETHOD: system_controller system setTestPlugins true Feb 18 18:32:11 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Feb 18 18:32:11 volumio volumio[820]: info: Plugins store is now in test mode Feb 18 18:32:20 volumio volumio[820]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.18 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.5 Safari/605.1.15 Total Clients: 3 Feb 18 18:32:20 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 18:32:20 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Feb 18 18:32:20 volumio volumio[820]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 18:32:20 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:32:22 volumio volumio[820]: info: Enabling plugin audiophonicsonoff Feb 18 18:32:22 volumio volumio[820]: info: Loading plugin "audiophonicsonoff"... Feb 18 18:32:22 volumio volumio[820]: info: Applying required configuration parameters for plugin audiophonicsonoff Feb 18 18:32:22 volumio volumio[820]: info: Audiophonics on/off initiated Feb 18 18:32:22 volumio volumio[820]: info: PLUGIN START: audiophonicsonoff Feb 18 18:32:22 volumio volumio[820]: info: Configuring GPIO pins Feb 18 18:32:22 volumio volumio[820]: info: Error: Error: EBUSY: resource busy or locked, write Feb 18 18:32:38 volumio volumio[820]: info: Starting Uninstall of plugin system_hardware - audiophonicsonoff Feb 18 18:32:38 volumio volumio[820]: info: Uninstalling plugin audiophonicsonoff Feb 18 18:32:38 volumio volumio[820]: info: performing onStop action Feb 18 18:32:38 volumio volumio[820]: info: Disabling plugin audiophonicsonoff Feb 18 18:32:38 volumio volumio[820]: info: Checking if uninstall.sh is present Feb 18 18:32:38 volumio volumio[820]: info: Executing uninstall.sh Feb 18 18:32:38 volumio sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/uninstall.sh Feb 18 18:32:38 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:32:38 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Feb 18 18:32:38 volumio volumio[820]: info: Uninstall script completed Feb 18 18:32:38 volumio volumio[820]: info: Removing plugin audiophonicsonoff from configuration Feb 18 18:32:38 volumio volumio[820]: info: Successfully removed audiophonicsonoff configuration files Feb 18 18:32:38 volumio volumio[820]: info: Plugin folders cleanup Feb 18 18:32:38 volumio volumio[820]: info: Scanning into folder /volumio/app/plugins/ Feb 18 18:32:38 volumio volumio[820]: info: Scanning category audio_interface Feb 18 18:32:38 volumio volumio[820]: info: Scanning category miscellanea Feb 18 18:32:38 volumio volumio[820]: info: Scanning category music_service Feb 18 18:32:38 volumio volumio[820]: info: Scanning category plugins.json Feb 18 18:32:38 volumio volumio[820]: info: Scanning category system_controller Feb 18 18:32:38 volumio volumio[820]: info: Scanning category user_interface Feb 18 18:32:38 volumio volumio[820]: info: Scanning into folder /data/plugins/ Feb 18 18:32:38 volumio volumio[820]: info: Scanning category system_hardware Feb 18 18:32:38 volumio volumio[820]: info: Cleaning folder for audiophonicsonoff Feb 18 18:32:38 volumio volumio[820]: info: Plugin folders cleanup completed Feb 18 18:32:38 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 18:32:38 volumio volumio[820]: info: [] Feb 18 18:32:38 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::servicePushState Feb 18 18:32:41 volumio volumio[820]: info: CoreStateMachine::pushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::servicePushState Feb 18 18:32:41 volumio volumio[820]: info: CoreStateMachine::pushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::servicePushState Feb 18 18:32:41 volumio volumio[820]: info: CoreStateMachine::pushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::servicePushState Feb 18 18:32:41 volumio volumio[820]: info: CoreStateMachine::pushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::servicePushState Feb 18 18:32:41 volumio volumio[820]: info: CoreStateMachine::pushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::servicePushState Feb 18 18:32:41 volumio volumio[820]: info: CoreStateMachine::pushState Feb 18 18:32:41 volumio volumio[820]: info: CoreCommandRouter::volumioPushState Feb 18 18:32:44 volumio volumio[820]: verbose: New Socket.io Connection to volumio.local from 192.168.1.18 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.5 Safari/605.1.15 Total Clients: 3 Feb 18 18:32:45 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Feb 18 18:32:45 volumio volumio[820]: info: CoreCommandRouter::volumioGetQueue Feb 18 18:32:45 volumio volumio[820]: info: CoreStateMachine::getQueue Feb 18 18:32:45 volumio volumio[820]: info: CorePlayQueue::getQueue Feb 18 18:32:45 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Feb 18 18:32:45 volumio volumio[820]: info: CALLMETHOD: system_controller system setTestPlugins true Feb 18 18:32:45 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Feb 18 18:32:45 volumio volumio[820]: info: Plugins store is now in test mode Feb 18 18:32:48 volumio volumio[820]: verbose: New Socket.io Connection to 192.168.1.19 from 192.168.1.18 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.5 Safari/605.1.15 Total Clients: 3 Feb 18 18:32:48 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 18:32:48 volumio volumio[820]: info: CoreCommandRouter::volumioGetState Feb 18 18:32:48 volumio volumio[820]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 18:32:48 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:33:10 volumio volumio[820]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/audiophonicsonoff/volumio/buster/armhf Feb 18 18:33:12 volumio volumio[820]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/audiophonicsonoff/volumio/buster/armhf Feb 18 18:33:12 volumio volumio[820]: info: Folder /tmp/plugins removed Feb 18 18:33:12 volumio volumio[820]: info: Check plugin dependencies Feb 18 18:33:12 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 18:33:12 volumio volumio[820]: info: Checking if plugin already exists Feb 18 18:33:12 volumio volumio[820]: info: Rename folder Feb 18 18:33:12 volumio volumio[820]: info: Folder /tmp/downloaded_plugin.zip removed Feb 18 18:33:12 volumio volumio[820]: info: Move to category Feb 18 18:33:14 volumio volumio[820]: info: Checking if install.sh is present Feb 18 18:33:14 volumio volumio[820]: info: Executing install.sh Feb 18 18:33:14 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/install.sh Feb 18 18:33:14 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:33:14 volumio volumio[820]: info: Installing Audiophonics on/off and its dependencies... Feb 18 18:33:14 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Feb 18 18:33:14 volumio volumio[820]: info: Install script completed Feb 18 18:33:14 volumio volumio[820]: info: Adding reference to registry Feb 18 18:33:14 volumio volumio[820]: info: Done installing plugin. Feb 18 18:33:14 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 18:33:14 volumio volumio[820]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 18 18:33:14 volumio volumio[820]: info: No packages needed, only node_modules are required Feb 18 18:33:14 volumio volumio[820]: Plugin install end detected on script Feb 18 18:33:14 volumio volumio[820]: info: Folder /tmp/plugins removed Feb 18 18:33:14 volumio volumio[820]: info: Folder /tmp/downloaded_plugin.zip removed Feb 18 18:33:14 volumio volumio[820]: info: Folder /data/temp removed Feb 18 18:33:15 volumio volumio[820]: info: Enabling plugin audiophonicsonoff Feb 18 18:33:15 volumio volumio[820]: info: Loading plugin "audiophonicsonoff"... Feb 18 18:33:15 volumio volumio[820]: info: Audiophonics on/off initiated Feb 18 18:33:15 volumio volumio[820]: info: PLUGIN START: audiophonicsonoff Feb 18 18:33:15 volumio volumio[820]: info: Configuring GPIO pins Feb 18 18:33:15 volumio volumio[820]: info: Error: Error: EBUSY: resource busy or locked, write Feb 18 18:33:40 volumio volumio[820]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Feb 18 18:33:40 volumio volumio[820]: info: PLUGIN onShutdown : networkfs Feb 18 18:33:40 volumio volumio[820]: info: PLUGIN onShutdown : audiophonicsonoff Feb 18 18:33:40 volumio volumio[820]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 18:33:40 volumio volumio[820]: TypeError: Cannot read property 'writeSync' of undefined Feb 18 18:33:40 volumio volumio[820]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Feb 18 18:33:40 volumio volumio[820]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Feb 18 18:33:40 volumio volumio[820]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Feb 18 18:33:40 volumio volumio[820]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 18 18:33:40 volumio volumio[820]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 18 18:33:40 volumio volumio[820]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Feb 18 18:33:40 volumio volumio[820]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1281:22) Feb 18 18:33:40 volumio volumio[820]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:767:33) Feb 18 18:33:40 volumio volumio[820]: at Socket.emit (events.js:315:20) Feb 18 18:33:40 volumio volumio[820]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 18 18:33:40 volumio volumio[820]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 18 18:33:40 volumio volumio[820]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 18:33:41 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-18 18:32 Feb 18 18:33:41 volumio sudo[1510]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"