-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Thu 2025-05-22 10:42:31 JST. -- May 22 10:41:54 volumio sudo[4532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 10:41:54 volumio sudo[4532]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 10:41:54 volumio sudo[4536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 10:41:54 volumio sudo[4536]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 10:41:54 volumio sudo[4536]: pam_unix(sudo:session): session closed for user root May 22 10:41:54 volumio sudo[4532]: pam_unix(sudo:session): session closed for user root May 22 10:41:54 volumio volumio[936]: verbose: New Socket.io Connection to 192.168.2.135 from 192.168.2.85 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::volumioGetVisibleSources May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 10:41:54 volumio volumio[936]: info: Received Get System Info May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:41:54 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:41:55 volumio volumio[936]: info: Discovery: Getting this device information May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:41:55 volumio volumio[936]: info: Listing playlists May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:41:55 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 10:41:56 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 10:41:56 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 10:41:56 volumio volumio[936]: info: Received Get System Info May 22 10:41:56 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:41:56 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:41:56 volumio volumio[936]: info: Discovery: Getting this device information May 22 10:41:56 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:41:56 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:41:58 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 10:41:58 volumio volumio[936]: info: Received Get System Info May 22 10:41:58 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:41:58 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:41:58 volumio volumio[936]: info: Discovery: Getting this device information May 22 10:41:58 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:41:58 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:42:00 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 10:42:00 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 22 10:42:03 volumio volumio[936]: info: AutoStart - onStop May 22 10:42:03 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:42:03 volumio volumio[936]: info: Disabling plugin autostart May 22 10:42:03 volumio volumio[936]: info: Done. May 22 10:42:04 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::volumioStop May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::stop May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::stPlaybackTimer May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::updateTrackBlock May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrackBlock May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::pushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::volumioPushState May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::serviceStop May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::serviceStop May 22 10:42:07 volumio volumio[936]: info: [1747878127931] ControllerWebradio::stop May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand stop May 22 10:42:07 volumio volumio[936]: info: [ASDebug] CurState: stop PrevState: play May 22 10:42:07 volumio volumio[936]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 10:42:07 volumio volumio[936]: info: May 22 10:42:07 volumio volumio[936]: ---------------------------- MPD announces state update: player May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand stop took 28 milliseconds May 22 10:42:07 volumio volumio[936]: info: ControllerMpd::getState May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status May 22 10:42:07 volumio volumio[936]: info: May 22 10:42:07 volumio volumio[936]: ---------------------------- MPD announces state update: player May 22 10:42:07 volumio volumio[936]: info: ControllerMpd::getState May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status May 22 10:42:07 volumio volumio[936]: info: May 22 10:42:07 volumio volumio[936]: ---------------------------- MPD announces state update: player May 22 10:42:07 volumio volumio[936]: info: ControllerMpd::getState May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand status took 6 milliseconds May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand status took 4 milliseconds May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand status took 3 milliseconds May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::parseState May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::parseState May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::parseState May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand playlistinfo took 4 milliseconds May 22 10:42:07 volumio volumio[936]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::parseTrackInfo May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::parseTrackInfo May 22 10:42:07 volumio volumio[936]: verbose: ControllerMpd::parseTrackInfo May 22 10:42:07 volumio volumio[936]: info: ControllerMpd::pushState May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::servicePushState May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::pushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::volumioPushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 10:42:07 volumio volumio[936]: info: ControllerMpd::pushState May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::servicePushState May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::pushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::volumioPushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 10:42:07 volumio volumio[936]: info: ControllerMpd::pushState May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::servicePushState May 22 10:42:07 volumio volumio[936]: info: CoreStateMachine::pushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: CoreCommandRouter::volumioPushState May 22 10:42:07 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:07 volumio volumio[936]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 22 10:42:08 volumio volumio[936]: info: ------------------------------ 56ms May 22 10:42:08 volumio volumio[936]: info: ------------------------------ 53ms May 22 10:42:08 volumio volumio[936]: info: ------------------------------ 52ms May 22 10:42:08 volumio volumio[936]: info: [ASDebug] CurState: stop PrevState: play May 22 10:42:08 volumio volumio[936]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 10:42:08 volumio volumio[936]: info: [ASDebug] CurState: stop PrevState: play May 22 10:42:08 volumio volumio[936]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 10:42:08 volumio volumio[936]: info: [ASDebug] CurState: stop PrevState: play May 22 10:42:08 volumio volumio[936]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 10:42:08 volumio volumio[936]: info: [ASDebug] Togle GPIO: OFF May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 10:42:13 volumio volumio[936]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 10:42:13 volumio volumio[936]: info: Received Get System Version May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 10:42:13 volumio volumio[936]: info: Received Get System Info May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:42:13 volumio volumio[936]: info: Discovery: Getting this device information May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::volumioGetState May 22 10:42:13 volumio volumio[936]: info: CorePlayQueue::getTrack 0 May 22 10:42:13 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:42:17 volumio volumio[936]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:42:17 volumio volumio[936]: info: [ASDebug] Setting UI defaults May 22 10:42:17 volumio volumio[936]: info: [ASDebug] Port: 24 May 22 10:42:17 volumio volumio[936]: info: [ASDebug] Inverted: false May 22 10:42:17 volumio volumio[936]: info: [ASDebug] Latched: false May 22 10:42:17 volumio volumio[936]: info: [ASDebug] On pulse width: 500 May 22 10:42:17 volumio volumio[936]: info: [ASDebug] Off pulse width: 500 May 22 10:42:19 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 10:42:19 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 22 10:42:24 volumio volumio[936]: info: Starting Uninstall of plugin system_controller - ampswitch May 22 10:42:24 volumio volumio[936]: info: Uninstalling plugin ampswitch May 22 10:42:24 volumio volumio[936]: info: [ASDebug] Port: 24 May 22 10:42:24 volumio volumio[936]: info: [ASDebug] Inverted: false May 22 10:42:24 volumio volumio[936]: info: [ASDebug] Delay: 720 May 22 10:42:29 volumio volumio[936]: info: [ASDebug] Port: 24 May 22 10:42:29 volumio volumio[936]: info: [ASDebug] Inverted: false May 22 10:42:29 volumio volumio[936]: info: [ASDebug] Delay: 720 May 22 10:42:29 volumio volumio[936]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 10:42:30 volumio volumio[936]: Error: EBADF: bad file descriptor, close May 22 10:42:30 volumio volumio[936]: at Object.closeSync (fs.js:439:3) May 22 10:42:30 volumio volumio[936]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) May 22 10:42:30 volumio volumio[936]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) May 22 10:42:30 volumio volumio[936]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) May 22 10:42:30 volumio volumio[936]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) May 22 10:42:30 volumio volumio[936]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) May 22 10:42:30 volumio volumio[936]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 22 10:42:30 volumio volumio[936]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 22 10:42:30 volumio volumio[936]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 22 10:42:30 volumio volumio[936]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 22 10:42:30 volumio volumio[936]: at Socket.emit (events.js:315:20) May 22 10:42:30 volumio volumio[936]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 22 10:42:30 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 22 10:42:30 volumio volumio[936]: errno: -9, May 22 10:42:30 volumio volumio[936]: syscall: 'close', May 22 10:42:30 volumio volumio[936]: code: 'EBADF' May 22 10:42:30 volumio volumio[936]: } May 22 10:42:30 volumio volumio[936]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 10:42:31 volumio sudo[4680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 10:41 May 22 10:42:31 volumio sudo[4680]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"