-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-03-12 18:24:39 CET. -- Mar 12 18:23:00 volumio volumio[977]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 12 18:23:00 volumio ntpd[753]: Listen normally on 4 wlan0 192.168.0.105:123 Mar 12 18:23:00 volumio ntpd[753]: new interface(s) found: waking up resolver Mar 12 18:23:07 volumio volumio[977]: info: Volumio Network Manager: Network status updated: 2 Mar 12 18:23:07 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:07 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:10 volumio volumio[977]: error: error Mar 12 18:23:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 18:23:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 18:23:17 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:17 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:17 volumio volumio[977]: info: Listing playlists Mar 12 18:23:17 volumio volumio[977]: info: Listing playlists Mar 12 18:23:23 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 18:23:28 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:28 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:33 volumio volumio[977]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/audiophonicsonoff/volumio/buster/armhf Mar 12 18:23:34 volumio volumio[977]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/audiophonicsonoff/volumio/buster/armhf Mar 12 18:23:34 volumio volumio[977]: info: Folder /tmp/plugins removed Mar 12 18:23:35 volumio volumio[977]: info: Check plugin dependencies Mar 12 18:23:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 18:23:35 volumio volumio[977]: info: Checking if plugin already exists Mar 12 18:23:35 volumio volumio[977]: info: Rename folder Mar 12 18:23:35 volumio volumio[977]: info: Folder /tmp/downloaded_plugin.zip removed Mar 12 18:23:35 volumio volumio[977]: info: Move to category Mar 12 18:23:35 volumio volumio[977]: info: Checking if install.sh is present Mar 12 18:23:35 volumio volumio[977]: info: Executing install.sh Mar 12 18:23:35 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/install.sh Mar 12 18:23:35 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 18:23:35 volumio volumio[977]: info: Installing Audiophonics on/off and its dependencies... Mar 12 18:23:35 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Mar 12 18:23:35 volumio volumio[977]: info: Install script completed Mar 12 18:23:35 volumio volumio[977]: info: Adding reference to registry Mar 12 18:23:35 volumio volumio[977]: info: Done installing plugin. Mar 12 18:23:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 18:23:35 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 18:23:35 volumio volumio[977]: info: Folder /tmp/plugins removed Mar 12 18:23:35 volumio volumio[977]: info: Folder /tmp/downloaded_plugin.zip removed Mar 12 18:23:35 volumio volumio[977]: info: No packages needed, only node_modules are required Mar 12 18:23:35 volumio volumio[977]: Plugin install end detected on script Mar 12 18:23:35 volumio volumio[977]: info: Folder /data/temp removed Mar 12 18:23:37 volumio volumio[977]: info: Enabling plugin audiophonicsonoff Mar 12 18:23:37 volumio volumio[977]: info: Loading plugin "audiophonicsonoff"... Mar 12 18:23:37 volumio volumio[977]: info: Audiophonics on/off initiated Mar 12 18:23:37 volumio volumio[977]: info: PLUGIN START: audiophonicsonoff Mar 12 18:23:37 volumio volumio[977]: info: Configuring GPIO pins Mar 12 18:23:37 volumio volumio[977]: info: Error: Error: EBUSY: resource busy or locked, write Mar 12 18:23:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:38 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:38 volumio volumio[977]: info: Listing playlists Mar 12 18:23:38 volumio volumio[977]: info: Listing playlists Mar 12 18:23:43 volumio volumio[977]: info: Enabling plugin audiophonicsonoff Mar 12 18:23:43 volumio volumio[977]: info: Loading plugin "audiophonicsonoff"... Mar 12 18:23:43 volumio volumio[977]: info: Applying required configuration parameters for plugin audiophonicsonoff Mar 12 18:23:43 volumio volumio[977]: info: Audiophonics on/off initiated Mar 12 18:23:43 volumio volumio[977]: info: PLUGIN START: audiophonicsonoff Mar 12 18:23:43 volumio volumio[977]: info: Configuring GPIO pins Mar 12 18:23:43 volumio volumio[977]: info: Error: Error: EBUSY: resource busy or locked, write Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:48 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 18:23:48 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 18:23:48 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Mar 12 18:23:48 volumio sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 18:23:48 volumio sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 18:23:48 volumio sudo[2282]: pam_unix(sudo:session): session closed for user root Mar 12 18:23:48 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.98 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 18:23:48 volumio volumio[977]: info: Received Get System Info Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 18:23:48 volumio volumio[977]: info: Discovery: Getting this device information Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:48 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:48 volumio volumio[977]: info: Listing playlists Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 18:23:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 18:23:50 volumio volumio[977]: info: Received Get System Info Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 18:23:50 volumio volumio[977]: info: Discovery: Getting this device information Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 18:23:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 18:23:52 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 18:23:52 volumio volumio[977]: info: Loaded the previous config. Mar 12 18:23:52 volumio volumio[977]: info: ## populating UI... Mar 12 18:23:52 volumio volumio[977]: info: 1/1 settings loaded Mar 12 18:23:52 volumio volumio[977]: info: Populated config screen. Mar 12 18:23:53 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 18:23:53 volumio volumio[977]: info: Received Get System Info Mar 12 18:23:53 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 18:23:53 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 18:23:53 volumio volumio[977]: info: Discovery: Getting this device information Mar 12 18:23:53 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:53 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:53 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 18:23:55 volumio volumio[977]: info: CALLMETHOD: system_hardware audiophonicsonoff updateButtonConfig [object Object] Mar 12 18:23:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Mar 12 18:23:58 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:58 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:23:58 volumio volumio[977]: info: Listing playlists Mar 12 18:23:58 volumio volumio[977]: info: Listing playlists Mar 12 18:23:58 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 12 18:23:58 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:23:58 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:00 volumio volumio[977]: error: error Mar 12 18:24:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 18:24:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 18:24:06 volumio volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 12 18:24:06 volumio volumio[977]: info: Received Get System Version Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 18:24:06 volumio volumio[977]: info: Received Get System Info Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 18:24:06 volumio volumio[977]: info: Discovery: Getting this device information Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:24:06 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 18:24:08 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:24:08 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:09 volumio volumio[977]: info: Enabling plugin audiophonicsonoff Mar 12 18:24:09 volumio volumio[977]: info: Loading plugin "audiophonicsonoff"... Mar 12 18:24:09 volumio volumio[977]: info: Applying required configuration parameters for plugin audiophonicsonoff Mar 12 18:24:09 volumio volumio[977]: info: Audiophonics on/off initiated Mar 12 18:24:09 volumio volumio[977]: info: PLUGIN START: audiophonicsonoff Mar 12 18:24:09 volumio volumio[977]: info: Configuring GPIO pins Mar 12 18:24:09 volumio volumio[977]: info: Error: Error: EBUSY: resource busy or locked, write Mar 12 18:24:10 volumio volumio[977]: info: Enabling plugin audiophonicsonoff Mar 12 18:24:10 volumio volumio[977]: info: Loading plugin "audiophonicsonoff"... Mar 12 18:24:10 volumio volumio[977]: info: Applying required configuration parameters for plugin audiophonicsonoff Mar 12 18:24:10 volumio volumio[977]: info: Audiophonics on/off initiated Mar 12 18:24:10 volumio volumio[977]: info: PLUGIN START: audiophonicsonoff Mar 12 18:24:10 volumio volumio[977]: info: Configuring GPIO pins Mar 12 18:24:10 volumio volumio[977]: info: Error: Error: EBUSY: resource busy or locked, write Mar 12 18:24:12 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 18:24:12 volumio volumio[977]: info: Loaded the previous config. Mar 12 18:24:12 volumio volumio[977]: info: ## populating UI... Mar 12 18:24:12 volumio volumio[977]: info: 1/1 settings loaded Mar 12 18:24:12 volumio volumio[977]: info: Populated config screen. Mar 12 18:24:18 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:24:18 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:18 volumio volumio[977]: info: Listing playlists Mar 12 18:24:18 volumio volumio[977]: info: Listing playlists Mar 12 18:24:21 volumio volumio[977]: info: CALLMETHOD: system_hardware audiophonicsonoff updateButtonConfig [object Object] Mar 12 18:24:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: audiophonicsonoff , updateButtonConfig Mar 12 18:24:28 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:24:28 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:34 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:24:34 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Mar 12 18:24:38 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Mar 12 18:24:38 volumio volumio[977]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Mar 12 18:24:38 volumio volumio[977]: info: PLUGIN onShutdown : networkfs Mar 12 18:24:38 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/NAS Mar 12 18:24:38 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 18:24:38 volumio systemd[1]: mnt-NAS-NAS.mount: Succeeded. Mar 12 18:24:38 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Mar 12 18:24:38 volumio volumio[977]: info: PLUGIN onShutdown : touch_display Mar 12 18:24:38 volumio sudo[2422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 12 18:24:38 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 18:24:38 volumio volumio[977]: info: PLUGIN onShutdown : audiophonicsonoff Mar 12 18:24:38 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 18:24:38 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Mar 12 18:24:38 volumio volumio[977]: TypeError: Cannot read property 'writeSync' of undefined Mar 12 18:24:38 volumio volumio[977]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Mar 12 18:24:38 volumio volumio[977]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Mar 12 18:24:38 volumio volumio[977]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Mar 12 18:24:38 volumio volumio[977]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 12 18:24:38 volumio volumio[977]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 12 18:24:38 volumio volumio[977]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Mar 12 18:24:38 volumio volumio[977]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Mar 12 18:24:38 volumio volumio[977]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Mar 12 18:24:38 volumio volumio[977]: at Socket.emit (events.js:315:20) Mar 12 18:24:38 volumio volumio[977]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 12 18:24:38 volumio volumio[977]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 12 18:24:38 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 18:24:38 volumio sudo[2425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Mar 12 18:24:38 volumio sudo[2425]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 18:24:38 volumio systemd[1]: Stopping Volumio Kiosk... Mar 12 18:24:38 volumio startx[1292]: xinit: connection to X server lost Mar 12 18:24:38 volumio startx[1292]: [1B blob data] Mar 12 18:24:38 volumio startx[1292]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Mar 12 18:24:39 volumio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 18:23 Mar 12 18:24:39 volumio sudo[2439]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"