-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2025-06-15 23:39:32 UTC. -- Jun 15 23:38:09 volumio volumio[640]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 15 23:38:09 volumio volumio[640]: info: Completed starting MyVolumio Plugin Jun 15 23:38:09 volumio volumio[640]: info: BOOT COMPLETED Jun 15 23:38:09 volumio volumio[640]: [Metrics] CommandRouter: 80s 73.80ms Jun 15 23:38:09 volumio volumio[640]: info: CoreCommandRouter::volumiosetStartupVolume Jun 15 23:38:09 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 15 23:38:09 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 15 23:38:09 volumio volumio[640]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:38:09 volumio volumio[640]: info: CoreCommandRouter::Close All Modals sent Jun 15 23:38:10 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 15 23:38:10 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 15 23:38:10 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 15 23:39:17 volumio volumio[640]: verbose: New Socket.io Connection to 192.168.0.11 from 192.168.0.109 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Total Clients: 3 Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::volumioGetVisibleSources Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::volumioGetState Jun 15 23:39:18 volumio volumio[640]: info: CorePlayQueue::getTrack 0 Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::volumioGetQueue Jun 15 23:39:18 volumio volumio[640]: info: CoreStateMachine::getQueue Jun 15 23:39:18 volumio volumio[640]: info: CorePlayQueue::getQueue Jun 15 23:39:18 volumio volumio[640]: info: Listing playlists Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 15 23:39:18 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 15 23:39:20 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: jp_radio , handleBrowseUri Jun 15 23:39:31 volumio volumio[640]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::ClearQueue Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::stop Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 23:39:31 volumio volumio[640]: verbose: UNSET VOLATILE Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::clearPlayQueue Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::saveQueue Jun 15 23:39:31 volumio volumio[640]: info: CoreCommandRouter::volumioPushState Jun 15 23:39:31 volumio volumio[640]: info: CoreCommandRouter::volumioPushQueue Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::addQueueItems Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::addQueueItems Jun 15 23:39:31 volumio volumio[640]: info: Adding Item to queue: http://localhost:9000/radiko/JOAK-FM Jun 15 23:39:31 volumio volumio[640]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::saveQueue Jun 15 23:39:31 volumio volumio[640]: info: CoreCommandRouter::volumioPushQueue Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::updateTrackBlock Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::getTrackBlock Jun 15 23:39:31 volumio volumio[640]: info: CoreCommandRouter::volumioPlay Jun 15 23:39:31 volumio volumio[640]: verbose: UNSET VOLATILE Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::play index 0 Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::stop Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 23:39:31 volumio volumio[640]: verbose: UNSET VOLATILE Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::play index undefined Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::getTrack 0 Jun 15 23:39:31 volumio volumio[640]: info: CoreStateMachine::startPlaybackTimer Jun 15 23:39:31 volumio volumio[640]: info: CorePlayQueue::getTrack 0 Jun 15 23:39:31 volumio volumio[640]: info: [1750030771614] ControllerWebradio::clearAddPlayTrack Jun 15 23:39:31 volumio volumio[640]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 23:39:31 volumio volumio[640]: info: sendMpdCommand stop took 15 milliseconds Jun 15 23:39:31 volumio volumio[640]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 23:39:31 volumio volumio[640]: info: Jun 15 23:39:31 volumio volumio[640]: ---------------------------- MPD announces system playlist update Jun 15 23:39:31 volumio volumio[640]: info: Ignoring MPD Status Update Jun 15 23:39:31 volumio volumio[640]: info: sendMpdCommand clear took 6 milliseconds Jun 15 23:39:31 volumio volumio[640]: verbose: ControllerMpd::sendMpdCommand load "http://localhost:9000/radiko/JOAK-FM" Jun 15 23:39:31 volumio volumio[640]: info: Jun 15 23:39:31 volumio volumio[640]: ---------------------------- MPD announces system playlist update Jun 15 23:39:31 volumio volumio[640]: info: Ignoring MPD Status Update Jun 15 23:39:31 volumio volumio[640]: info: Jun 15 23:39:31 volumio volumio[640]: ---------------------------- MPD announces system playlist update Jun 15 23:39:31 volumio volumio[640]: info: Ignoring MPD Status Update Jun 15 23:39:31 volumio volumio[640]: error: updateQueue error: null Jun 15 23:39:31 volumio volumio[640]: info: ------------------------------ 33ms Jun 15 23:39:31 volumio volumio[640]: info: Jun 15 23:39:31 volumio volumio[640]: ---------------------------- MPD announces system playlist update Jun 15 23:39:31 volumio volumio[640]: info: Ignoring MPD Status Update Jun 15 23:39:31 volumio volumio[640]: error: Upnp client error: Error: This socket has been ended by the other party Jun 15 23:39:31 volumio volumio[640]: info: JP_Radio::Streaming started Jun 15 23:39:31 volumio volumio[640]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 23:39:31 volumio volumio[640]: Error: spawn ffmpeg ENOENT Jun 15 23:39:31 volumio volumio[640]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jun 15 23:39:31 volumio volumio[640]: at onErrorNT (internal/child_process.js:465:16) Jun 15 23:39:31 volumio volumio[640]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jun 15 23:39:31 volumio volumio[640]: errno: -2, Jun 15 23:39:31 volumio volumio[640]: code: 'ENOENT', Jun 15 23:39:31 volumio volumio[640]: syscall: 'spawn ffmpeg', Jun 15 23:39:31 volumio volumio[640]: path: 'ffmpeg', Jun 15 23:39:31 volumio volumio[640]: spawnargs: [ Jun 15 23:39:31 volumio volumio[640]: '-y', Jun 15 23:39:31 volumio volumio[640]: '-headers', Jun 15 23:39:31 volumio volumio[640]: 'X-Radiko-Authtoken:yeDszPJdmr3h2VEcjIqXPg', Jun 15 23:39:31 volumio volumio[640]: '-i', Jun 15 23:39:31 volumio volumio[640]: 'https://f-radiko.smartstream.ne.jp/JOAK-FM/_definst_/simul-stream.stream/chunklist_w520653896.m3u8', Jun 15 23:39:31 volumio volumio[640]: '-acodec', Jun 15 23:39:31 volumio volumio[640]: 'copy', Jun 15 23:39:31 volumio volumio[640]: '-f', Jun 15 23:39:31 volumio volumio[640]: 'adts', Jun 15 23:39:31 volumio volumio[640]: '-loglevel', Jun 15 23:39:31 volumio volumio[640]: 'error', Jun 15 23:39:31 volumio volumio[640]: 'pipe:1' Jun 15 23:39:31 volumio volumio[640]: ] Jun 15 23:39:31 volumio volumio[640]: } Jun 15 23:39:31 volumio volumio[640]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 23:39:32 volumio sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 23:38 Jun 15 23:39:32 volumio sudo[934]: 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"