-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Sat 2025-10-25 19:55:08 BST. -- Oct 25 19:54:00 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 25 19:54:00 volumio volumio[1004]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100780 Oct 25 19:54:00 volumio volumio[1004]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100780 Oct 25 19:54:00 volumio volumio[1004]: error: Cannot read property 'split' of undefined Oct 25 19:54:00 volumio volumio[1004]: error: Failed to execute browseSource: Error: Cannot list category items for tunein/browse/?id=r100780: TypeError: Cannot read property 'split' of undefined Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::volumioStop Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::stop Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::serviceStop Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::serviceStop Oct 25 19:54:07 volumio volumio[1004]: info: [1761418447777] ControllerWebradio::stop Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] CurState: stop PrevState: play Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand stop took 16 milliseconds Oct 25 19:54:07 volumio volumio[1004]: info: Oct 25 19:54:07 volumio volumio[1004]: ---------------------------- MPD announces state update: player Oct 25 19:54:07 volumio volumio[1004]: info: ControllerMpd::getState Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:54:07 volumio volumio[1004]: info: Oct 25 19:54:07 volumio volumio[1004]: ---------------------------- MPD announces state update: player Oct 25 19:54:07 volumio volumio[1004]: info: ControllerMpd::getState Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:54:07 volumio volumio[1004]: info: Oct 25 19:54:07 volumio volumio[1004]: ---------------------------- MPD announces state update: player Oct 25 19:54:07 volumio volumio[1004]: info: ControllerMpd::getState Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand status took 5 milliseconds Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand status took 2 milliseconds Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand status took 1 milliseconds Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::parseState Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::parseState Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::parseState Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 19:54:07 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:54:07 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Oct 25 19:54:07 volumio volumio[1004]: info: ControllerMpd::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 25 19:54:07 volumio volumio[1004]: info: ControllerMpd::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 25 19:54:07 volumio volumio[1004]: info: ControllerMpd::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Oct 25 19:54:07 volumio volumio[1004]: info: CoreStateMachine::pushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Oct 25 19:54:07 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:07 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 25 19:54:07 volumio volumio[1004]: info: ------------------------------ 33ms Oct 25 19:54:07 volumio volumio[1004]: info: ------------------------------ 31ms Oct 25 19:54:07 volumio volumio[1004]: info: ------------------------------ 30ms Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] CurState: stop PrevState: play Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] CurState: stop PrevState: play Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] CurState: stop PrevState: play Oct 25 19:54:07 volumio volumio[1004]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 25 19:54:08 volumio volumio[1004]: info: [ASDebug] Togle GPIO: OFF Oct 25 19:54:10 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 19:54:10 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 19:54:10 volumio volumio[1004]: info: Discovery: Getting this device information Oct 25 19:54:10 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:10 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:10 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 19:54:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 19:54:40 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 19:54:40 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 19:54:40 volumio volumio[1004]: info: Discovery: Getting this device information Oct 25 19:54:40 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:40 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:40 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 19:54:41 volumio volumio[1004]: verbose: New Socket.io Connection to 192.168.2.188 from 192.168.2.151 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.0.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 Oct 25 19:54:41 volumio volumio[1004]: info: Received Get System Info Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 19:54:41 volumio volumio[1004]: info: Discovery: Getting this device information Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 19:54:41 volumio volumio[1004]: info: Received Get System Info Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 19:54:41 volumio volumio[1004]: info: Discovery: Getting this device information Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Oct 25 19:54:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Oct 25 19:54:41 volumio volumio[1004]: info: Listing playlists Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Oct 25 19:54:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Oct 25 19:54:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Oct 25 19:54:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 25 19:54:58 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 19:54:58 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 19:55:05 volumio volumio[1004]: info: [ASDebug] Port: 24 Oct 25 19:55:05 volumio volumio[1004]: info: [ASDebug] Inverted: false Oct 25 19:55:05 volumio volumio[1004]: info: [ASDebug] Delay: 720 Oct 25 19:55:08 volumio volumio[1004]: info: Starting Uninstall of plugin system_controller - ampswitch Oct 25 19:55:08 volumio volumio[1004]: info: Uninstalling plugin ampswitch Oct 25 19:55:08 volumio volumio[1004]: info: [ASDebug] Port: 24 Oct 25 19:55:08 volumio volumio[1004]: info: [ASDebug] Inverted: false Oct 25 19:55:08 volumio volumio[1004]: info: [ASDebug] Delay: 720 Oct 25 19:55:08 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 19:55:08 volumio volumio[1004]: Error: EBADF: bad file descriptor, close Oct 25 19:55:08 volumio volumio[1004]: at Object.closeSync (fs.js:439:3) Oct 25 19:55:08 volumio volumio[1004]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Oct 25 19:55:08 volumio volumio[1004]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Oct 25 19:55:08 volumio volumio[1004]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Oct 25 19:55:08 volumio volumio[1004]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Oct 25 19:55:08 volumio volumio[1004]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Oct 25 19:55:08 volumio volumio[1004]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 25 19:55:08 volumio volumio[1004]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 25 19:55:08 volumio volumio[1004]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Oct 25 19:55:08 volumio volumio[1004]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Oct 25 19:55:08 volumio volumio[1004]: at Socket.emit (events.js:315:20) Oct 25 19:55:08 volumio volumio[1004]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 25 19:55:08 volumio volumio[1004]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 25 19:55:08 volumio volumio[1004]: errno: -9, Oct 25 19:55:08 volumio volumio[1004]: syscall: 'close', Oct 25 19:55:08 volumio volumio[1004]: code: 'EBADF' Oct 25 19:55:08 volumio volumio[1004]: } Oct 25 19:55:08 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 19:55:08 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 19:54 Oct 25 19:55:08 volumio sudo[1995]: 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"