-- Logs begin at Sat 2024-10-12 08:00:04 CEST, end at Sat 2024-10-12 15:26:35 CEST. -- Oct 12 15:26:10 volumio sudo[19802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 15:26:10 volumio sudo[19802]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 15:26:10 volumio sudo[19802]: pam_unix(sudo:session): session closed for user root Oct 12 15:26:10 volumio sudo[19805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 15:26:10 volumio sudo[19805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 15:26:10 volumio sudo[19805]: pam_unix(sudo:session): session closed for user root Oct 12 15:26:10 volumio volumio[992]: verbose: New Socket.io Connection to 192.168.5.80 from 192.168.5.2 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 15:26:11 volumio sudo[19808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 15:26:11 volumio sudo[19808]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 15:26:11 volumio sudo[19808]: pam_unix(sudo:session): session closed for user root Oct 12 15:26:11 volumio sudo[19811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 15:26:11 volumio sudo[19811]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 15:26:11 volumio sudo[19811]: pam_unix(sudo:session): session closed for user root Oct 12 15:26:11 volumio volumio[992]: verbose: New Socket.io Connection to 192.168.5.80 from 192.168.5.2 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 15:26:11 volumio volumio[992]: info: Received Get System Info Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 15:26:11 volumio volumio[992]: info: Discovery: Getting this device information Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Oct 12 15:26:11 volumio volumio[992]: info: Listing playlists Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 15:26:11 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 15:26:12 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 15:26:12 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 15:26:13 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 15:26:13 volumio volumio[992]: info: Received Get System Info Oct 12 15:26:13 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 15:26:13 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 15:26:13 volumio volumio[992]: info: Discovery: Getting this device information Oct 12 15:26:13 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Oct 12 15:26:13 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 15:26:13 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 15:26:14 volumio volumio[992]: info: Received Get System Info Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 15:26:14 volumio volumio[992]: info: Discovery: Getting this device information Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 15:26:14 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 15:26:19 volumio volumio[992]: info: Disabling plugin Systeminfo Oct 12 15:26:19 volumio volumio[992]: info: Done. Oct 12 15:26:21 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 12 15:26:23 volumio volumio[992]: info: [ASDebug] Port: 17 Oct 12 15:26:23 volumio volumio[992]: info: [ASDebug] Inverted: true Oct 12 15:26:23 volumio volumio[992]: info: [ASDebug] Delay: 100 Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 15:26:29 volumio volumio[992]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 12 15:26:29 volumio volumio[992]: info: Received Get System Version Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 15:26:29 volumio volumio[992]: info: Received Get System Info Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 15:26:29 volumio volumio[992]: info: Discovery: Getting this device information Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::volumioGetState Oct 12 15:26:29 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::volumioStop Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::stop Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::stPlaybackTimer Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::updateTrackBlock Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrackBlock Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::pushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::serviceStop Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::serviceStop Oct 12 15:26:35 volumio volumio[992]: info: [1728739595401] ControllerWebradio::stop Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] CurState: stop PrevState: play Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] InitTimeout - Amp off in: 100 ms Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand stop took 4 milliseconds Oct 12 15:26:35 volumio volumio[992]: info: Oct 12 15:26:35 volumio volumio[992]: ---------------------------- MPD announces state update: player Oct 12 15:26:35 volumio volumio[992]: info: ControllerMpd::getState Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:26:35 volumio volumio[992]: info: Oct 12 15:26:35 volumio volumio[992]: ---------------------------- MPD announces state update: player Oct 12 15:26:35 volumio volumio[992]: info: ControllerMpd::getState Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:26:35 volumio volumio[992]: info: Oct 12 15:26:35 volumio volumio[992]: ---------------------------- MPD announces state update: player Oct 12 15:26:35 volumio volumio[992]: info: ControllerMpd::getState Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand status Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand status took 2 milliseconds Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand status took 1 milliseconds Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand status took 0 milliseconds Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::parseState Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::parseState Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::parseState Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 15:26:35 volumio volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:26:35 volumio volumio[992]: verbose: ControllerMpd::parseTrackInfo Oct 12 15:26:35 volumio volumio[992]: info: ControllerMpd::pushState Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::servicePushState Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::pushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 12 15:26:35 volumio volumio[992]: info: ControllerMpd::pushState Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::servicePushState Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::pushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 12 15:26:35 volumio volumio[992]: info: ControllerMpd::pushState Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::servicePushState Oct 12 15:26:35 volumio volumio[992]: info: CoreStateMachine::pushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: CoreCommandRouter::volumioPushState Oct 12 15:26:35 volumio volumio[992]: info: CorePlayQueue::getTrack 2 Oct 12 15:26:35 volumio volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 12 15:26:35 volumio volumio[992]: info: ------------------------------ 11ms Oct 12 15:26:35 volumio volumio[992]: info: ------------------------------ 10ms Oct 12 15:26:35 volumio volumio[992]: info: ------------------------------ 10ms Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] CurState: stop PrevState: play Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] InitTimeout - Amp off in: 100 ms Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] CurState: stop PrevState: play Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] InitTimeout - Amp off in: 100 ms Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] CurState: stop PrevState: play Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] InitTimeout - Amp off in: 100 ms Oct 12 15:26:35 volumio volumio[992]: info: [ASDebug] Togle GPIO: OFF Oct 12 15:26:35 volumio volumio[992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 15:26:35 volumio volumio[992]: Error: EBADF: bad file descriptor, write Oct 12 15:26:35 volumio volumio[992]: at Object.writeSync (fs.js:711:3) Oct 12 15:26:35 volumio volumio[992]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Oct 12 15:26:35 volumio volumio[992]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:215:23) Oct 12 15:26:35 volumio volumio[992]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:187:88) Oct 12 15:26:35 volumio volumio[992]: at listOnTimeout (internal/timers.js:554:17) Oct 12 15:26:35 volumio volumio[992]: at processTimers (internal/timers.js:497:7) { Oct 12 15:26:35 volumio volumio[992]: errno: -9, Oct 12 15:26:35 volumio volumio[992]: syscall: 'write', Oct 12 15:26:35 volumio volumio[992]: code: 'EBADF' Oct 12 15:26:35 volumio volumio[992]: } Oct 12 15:26:35 volumio volumio[992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 15:26:35 volumio sudo[19824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 15:25 Oct 12 15:26:35 volumio sudo[19824]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"