-- Logs begin at Thu 2019-02-14 11:12:01 CET, end at Sun 2024-09-01 13:09:37 CEST. -- Sep 01 13:08:02 volumio volumio[980]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.94 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 01 13:08:02 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:02 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:02 volumio volumio[980]: info: CoreCommandRouter::volumioGetQueue Sep 01 13:08:02 volumio volumio[980]: info: CoreStateMachine::getQueue Sep 01 13:08:02 volumio volumio[980]: info: CorePlayQueue::getQueue Sep 01 13:08:02 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 01 13:08:02 volumio volumio[980]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Sep 01 13:08:02 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Sep 01 13:08:02 volumio volumio[980]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Sep 01 13:08:02 volumio volumio[980]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Sep 01 13:08:02 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Sep 01 13:08:07 volumio volumio[980]: info: CALLMETHOD: system_controller system enableSSH true Sep 01 13:08:07 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Sep 01 13:08:07 volumio sudo[2470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Sep 01 13:08:07 volumio sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:07 volumio sudo[2470]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:07 volumio sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Sep 01 13:08:07 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:08 volumio systemd[1]: Reloading. Sep 01 13:08:08 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 01 13:08:10 volumio systemd[1]: Reloading. Sep 01 13:08:10 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 01 13:08:11 volumio systemd[1]: Reloading. Sep 01 13:08:12 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 01 13:08:13 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:13 volumio volumio[980]: info: enable SSH service success Sep 01 13:08:14 volumio volumio[980]: info: CALLMETHOD: system_controller system setTestPlugins true Sep 01 13:08:14 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Sep 01 13:08:14 volumio volumio[980]: info: Plugins store is now in test mode Sep 01 13:08:20 volumio sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 13:08:20 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:20 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:20 volumio sudo[2526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 13:08:20 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:20 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:21 volumio sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 13:08:21 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:21 volumio sudo[2530]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:21 volumio sudo[2532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 13:08:21 volumio sudo[2532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:21 volumio sudo[2532]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:21 volumio volumio[980]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.94 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:21 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 13:08:21 volumio volumio[980]: info: Received Get System Info Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 13:08:21 volumio volumio[980]: info: Discovery: Getting this device information Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:21 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:21 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:21 volumio volumio[980]: info: Listing playlists Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 13:08:21 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 13:08:22 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:22 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:22 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 13:08:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 13:08:23 volumio volumio[980]: info: Received Get System Info Sep 01 13:08:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 13:08:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 13:08:23 volumio volumio[980]: info: Discovery: Getting this device information Sep 01 13:08:23 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:23 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:23 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 13:08:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 13:08:24 volumio volumio[980]: info: Received Get System Info Sep 01 13:08:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 13:08:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 13:08:24 volumio volumio[980]: info: Discovery: Getting this device information Sep 01 13:08:24 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:24 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 13:08:26 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 13:08:26 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 13:08:31 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 13:08:35 volumio volumio[980]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ampswitch/volumio/buster/armhf Sep 01 13:08:37 volumio volumio[980]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ampswitch/volumio/buster/armhf Sep 01 13:08:37 volumio volumio[980]: info: Folder /tmp/plugins removed Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 13:08:39 volumio volumio[980]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 01 13:08:39 volumio volumio[980]: info: Received Get System Version Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 13:08:39 volumio volumio[980]: info: Received Get System Info Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 13:08:39 volumio volumio[980]: info: Discovery: Getting this device information Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:08:39 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:08:39 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 13:08:41 volumio volumio[980]: info: Check plugin dependencies Sep 01 13:08:41 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 13:08:41 volumio volumio[980]: info: Checking if plugin already exists Sep 01 13:08:41 volumio volumio[980]: info: Rename folder Sep 01 13:08:41 volumio volumio[980]: info: Folder /tmp/downloaded_plugin.zip removed Sep 01 13:08:41 volumio volumio[980]: info: Move to category Sep 01 13:08:49 volumio volumio[980]: info: Checking if install.sh is present Sep 01 13:08:49 volumio volumio[980]: info: Executing install.sh Sep 01 13:08:50 volumio sudo[2550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/ampswitch/install.sh Sep 01 13:08:50 volumio sudo[2550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 13:08:50 volumio sudo[2550]: pam_unix(sudo:session): session closed for user root Sep 01 13:08:50 volumio volumio[980]: info: Install script completed Sep 01 13:08:50 volumio volumio[980]: info: Adding reference to registry Sep 01 13:08:50 volumio volumio[980]: info: Done installing plugin. Sep 01 13:08:50 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 13:08:50 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 13:08:50 volumio volumio[980]: Plugin install end detected on script Sep 01 13:08:50 volumio volumio[980]: info: Folder /tmp/plugins removed Sep 01 13:08:50 volumio volumio[980]: info: Folder /tmp/downloaded_plugin.zip removed Sep 01 13:08:50 volumio volumio[980]: info: Folder /data/temp removed Sep 01 13:09:08 volumio volumio[980]: info: Enabling plugin ampswitch Sep 01 13:09:08 volumio volumio[980]: info: Loading plugin "ampswitch"... Sep 01 13:09:09 volumio volumio[980]: info: PLUGIN START: ampswitch Sep 01 13:09:09 volumio volumio[980]: info: Done. Sep 01 13:09:09 volumio volumio[980]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 01 13:09:09 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Sep 01 13:09:09 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Sep 01 13:09:09 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: na Sep 01 13:09:09 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 01 13:09:09 volumio volumio[980]: info: [ASDebug] CurState: stop PrevState: na Sep 01 13:09:09 volumio volumio[980]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 01 13:09:10 volumio volumio[980]: info: [ASDebug] Togle GPIO: OFF Sep 01 13:09:11 volumio volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 13:09:11 volumio volumio[980]: info: [ASDebug] Setting UI defaults Sep 01 13:09:11 volumio volumio[980]: info: [ASDebug] Port: 24 Sep 01 13:09:11 volumio volumio[980]: info: [ASDebug] Inverted: false Sep 01 13:09:11 volumio volumio[980]: info: [ASDebug] Latched: false Sep 01 13:09:11 volumio volumio[980]: info: [ASDebug] On pulse width: 500 Sep 01 13:09:11 volumio volumio[980]: info: [ASDebug] Off pulse width: 500 Sep 01 13:09:16 volumio volumio[980]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Sep 01 13:09:16 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Sep 01 13:09:16 volumio volumio[980]: info: [ASDebug] Saving Settings: Port: 24 Sep 01 13:09:16 volumio volumio[980]: info: [ASDebug] Saving Settings: Inverted: true Sep 01 13:09:16 volumio volumio[980]: info: [ASDebug] Saving Settings: Delay: 720 Sep 01 13:09:16 volumio volumio[980]: info: [ASDebug] Saving Settings: Latched: false Sep 01 13:09:16 volumio volumio[980]: info: [ASDebug] Saving Settings: On Pulse width: 500 Sep 01 13:09:16 volumio volumio[980]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Sep 01 13:09:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 13:09:24 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 01 13:09:32 volumio volumio[980]: info: [ASDebug] Port: 24 Sep 01 13:09:32 volumio volumio[980]: info: [ASDebug] Inverted: true Sep 01 13:09:32 volumio volumio[980]: info: [ASDebug] Delay: 720 Sep 01 13:09:36 volumio volumio[980]: info: [ASDebug] Port: 24 Sep 01 13:09:36 volumio volumio[980]: info: [ASDebug] Inverted: true Sep 01 13:09:36 volumio volumio[980]: info: [ASDebug] Delay: 720 Sep 01 13:09:36 volumio volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 13:09:36 volumio volumio[980]: Error: EBADF: bad file descriptor, close Sep 01 13:09:36 volumio volumio[980]: at Object.closeSync (fs.js:439:3) Sep 01 13:09:36 volumio volumio[980]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Sep 01 13:09:36 volumio volumio[980]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Sep 01 13:09:36 volumio volumio[980]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19) Sep 01 13:09:36 volumio volumio[980]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10) Sep 01 13:09:36 volumio volumio[980]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 01 13:09:36 volumio volumio[980]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 01 13:09:36 volumio volumio[980]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Sep 01 13:09:36 volumio volumio[980]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1380:47) Sep 01 13:09:36 volumio volumio[980]: at Socket.emit (events.js:315:20) Sep 01 13:09:36 volumio volumio[980]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 01 13:09:36 volumio volumio[980]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 01 13:09:36 volumio volumio[980]: errno: -9, Sep 01 13:09:36 volumio volumio[980]: syscall: 'close', Sep 01 13:09:36 volumio volumio[980]: code: 'EBADF' Sep 01 13:09:36 volumio volumio[980]: } Sep 01 13:09:36 volumio volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 13:09:37 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 13:08 Sep 01 13:09:37 volumio sudo[2621]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"