-- Logs begin at Thu 2025-09-04 01:54:18 JST, end at Thu 2025-09-04 01:56:19 JST. -- Sep 04 01:55:03 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 01:55:03 kazuhiropi5161 volumio[1244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 04 01:55:03 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::volumioGetState Sep 04 01:55:03 kazuhiropi5161 volumio[1244]: info: CorePlayQueue::getTrack 0 Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::volumioGetBrowseSources Sep 04 01:55:08 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: Completed starting MyVolumio Plugin Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: [Metrics] CommandRouter: 31s 859.38ms Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::volumiosetStartupVolume Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: VolumeController:: Setting startup Volume 100 Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: VolumeController::SetAlsaVolume100 Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::Close All Modals sent Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::Close All Modals sent Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreStateMachine::pushState Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CorePlayQueue::getTrack 0 Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::volumioPushState Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: [ASDebug] CurState: stop PrevState: stop Sep 04 01:55:09 kazuhiropi5161 volumio-remote-updater[899]: No test mode Sep 04 01:55:09 kazuhiropi5161 volumio-remote-updater[899]: No alpha test mode Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 04 01:55:09 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 04 01:55:10 kazuhiropi5161 systemd[1]: systemd-timedated.service: Succeeded. Sep 04 01:55:10 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 04 01:55:10 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 01:55:10 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 04 01:55:14 kazuhiropi5161 volumio[1244]: info: Starting Uninstall of plugin user_interface - touch_display Sep 04 01:55:14 kazuhiropi5161 volumio[1244]: info: Uninstalling plugin touch_display Sep 04 01:55:14 kazuhiropi5161 volumio[1244]: info: Disabling plugin touch_display Sep 04 01:55:14 kazuhiropi5161 volumio[1244]: info: Checking if uninstall.sh is present Sep 04 01:55:14 kazuhiropi5161 volumio[1244]: info: Executing uninstall.sh Sep 04 01:55:14 kazuhiropi5161 sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/uninstall.sh Sep 04 01:55:14 kazuhiropi5161 sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 01:55:16 kazuhiropi5161 volumio[1244]: info: BOOT COMPLETED Sep 04 01:55:26 kazuhiropi5161 ntpd[1034]: Soliciting pool server 162.159.200.1 Sep 04 01:55:27 kazuhiropi5161 ntpd[1034]: Soliciting pool server 129.250.35.251 Sep 04 01:55:27 kazuhiropi5161 ntpd[1034]: Soliciting pool server 167.179.119.205 Sep 04 01:55:28 kazuhiropi5161 ntpd[1034]: Soliciting pool server 162.159.200.123 Sep 04 01:55:28 kazuhiropi5161 ntpd[1034]: Soliciting pool server 142.91.105.55 Sep 04 01:55:28 kazuhiropi5161 ntpd[1034]: Soliciting pool server 160.16.113.133 Sep 04 01:55:29 kazuhiropi5161 ntpd[1034]: Soliciting pool server 122.215.240.51 Sep 04 01:55:29 kazuhiropi5161 ntpd[1034]: Soliciting pool server 160.25.135.55 Sep 04 01:55:29 kazuhiropi5161 ntpd[1034]: Soliciting pool server 45.76.211.39 Sep 04 01:55:29 kazuhiropi5161 ntpd[1034]: Soliciting pool server 203.135.216.218 Sep 04 01:55:30 kazuhiropi5161 ntpd[1034]: Soliciting pool server 108.160.132.224 Sep 04 01:55:30 kazuhiropi5161 ntpd[1034]: Soliciting pool server 129.250.35.250 Sep 04 01:55:30 kazuhiropi5161 ntpd[1034]: Soliciting pool server 46.232.60.193 Sep 04 01:55:31 kazuhiropi5161 ntpd[1034]: Soliciting pool server 64.176.59.83 Sep 04 01:55:31 kazuhiropi5161 ntpd[1034]: Soliciting pool server 139.162.81.45 Sep 04 01:55:32 kazuhiropi5161 ntpd[1034]: Soliciting pool server 103.131.151.20 Sep 04 01:55:33 kazuhiropi5161 ntpd[1034]: Soliciting pool server 2602:feda:6f:3e::a Sep 04 01:55:34 kazuhiropi5161 ntpd[1034]: receive: Unexpected origin timestamp 0xec62f185.39c602e8 does not match aorg 0000000000.00000000 from server@160.16.113.133 xmt 0xec62f186.7197152c Sep 04 01:55:34 kazuhiropi5161 ntpd[1034]: receive: Unexpected origin timestamp 0xec62f185.39c6758f does not match aorg 0000000000.00000000 from server@142.91.105.55 xmt 0xec62f186.72160386 Sep 04 01:55:34 kazuhiropi5161 ntpd[1034]: receive: Unexpected origin timestamp 0xec62f185.39c575c0 does not match aorg 0000000000.00000000 from server@203.135.216.218 xmt 0xec62f186.726d96b8 Sep 04 01:56:05 kazuhiropi5161 systemd[1]: Reloading. Sep 04 01:56:05 kazuhiropi5161 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 04 01:56:05 kazuhiropi5161 systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 04 01:56:05 kazuhiropi5161 systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 04 01:56:05 kazuhiropi5161 sudo[1740]: pam_unix(sudo:session): session closed for user root Sep 04 01:56:05 kazuhiropi5161 volumio[1244]: info: Uninstall script completed Sep 04 01:56:05 kazuhiropi5161 volumio[1244]: info: Removing plugin touch_display from configuration Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Successfully removed touch_display configuration files Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Plugin folders cleanup Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning into folder /volumio/app/plugins/ Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category audio_interface Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category miscellanea Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category music_service Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category plugins.json Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category system_controller Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category user_interface Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning into folder /data/plugins/ Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category system_controller Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Scanning category user_interface Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Cleaning folder for touch_display Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: Plugin folders cleanup completed Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: [{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 04 01:56:07 kazuhiropi5161 volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 01:56:14 kazuhiropi5161 volumio[1244]: info: Starting Uninstall of plugin system_controller - ampswitch Sep 04 01:56:14 kazuhiropi5161 volumio[1244]: info: Uninstalling plugin ampswitch Sep 04 01:56:14 kazuhiropi5161 volumio[1244]: info: [ASDebug] Port: 24 Sep 04 01:56:14 kazuhiropi5161 volumio[1244]: info: [ASDebug] Inverted: false Sep 04 01:56:14 kazuhiropi5161 volumio[1244]: info: [ASDebug] Delay: 720 Sep 04 01:56:14 kazuhiropi5161 systemd-udevd[414]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: info: [ASDebug] Port: 24 Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: info: [ASDebug] Inverted: false Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: info: [ASDebug] Delay: 720 Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: Error: EBADF: bad file descriptor, close Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at Object.closeSync (fs.js:439:3) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at Socket.emit (events.js:315:20) Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: errno: -9, Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: syscall: 'close', Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: code: 'EBADF' Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: } Sep 04 01:56:19 kazuhiropi5161 volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 01:56:19 kazuhiropi5161 sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 01:55 Sep 04 01:56:19 kazuhiropi5161 sudo[2828]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" 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 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"