-- Logs begin at Mon 2025-06-09 14:54:06 UTC, end at Mon 2025-06-09 16:02:42 UTC. -- Jun 09 16:01:57 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:01:57 volumio volumio[935]: info: CURURI: artists:// Jun 09 16:02:05 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:02:05 volumio volumio[935]: info: CURURI: music-library Jun 09 16:02:06 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:02:06 volumio volumio[935]: info: CURURI: music-library/NAS Jun 09 16:02:07 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:02:07 volumio volumio[935]: info: CURURI: music-library/NAS/CD Jun 09 16:02:07 volumio volumio[935]: info: Jun 09 16:02:07 volumio volumio[935]: ---------------------------- MPD announces state update: player Jun 09 16:02:07 volumio volumio[935]: info: ControllerMpd::getState Jun 09 16:02:07 volumio volumio[935]: info: ControllerMpd::sendMpdCommand status Jun 09 16:02:07 volumio volumio[935]: info: sending command... Jun 09 16:02:07 volumio volumio[935]: info: parsing response... Jun 09 16:02:07 volumio volumio[935]: info: ControllerMpd::parseState Jun 09 16:02:07 volumio volumio[935]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:02:07 volumio volumio[935]: info: sending command... Jun 09 16:02:07 volumio volumio[935]: info: parsing response... Jun 09 16:02:07 volumio volumio[935]: info: ControllerMpd::parseTrackInfo Jun 09 16:02:07 volumio volumio[935]: info: ControllerMpd::pushState Jun 09 16:02:08 volumio volumio[935]: info: CoreCommandRouter::servicePushState Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::syncState Jun 09 16:02:08 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:08 volumio volumio[935]: info: STATE SERVICE {"status":"play","position":1,"seek":23,"duration":132,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最後の征戦 ～霞む空の果て","artist":null,"album":"機動戦士ガンダムSEED DESTINY OST4","uri":"NAS/CD/[EAC]機動戦士ガンダムSEED DESTINY ORIGINAL SOUNDTRACK IV(WAV+CUE+LOG)/CDImage.wav","trackType":"wav"} Jun 09 16:02:08 volumio volumio[935]: info: CURRENT POSITION 0 Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:02:08 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::pushState Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::getState Jun 09 16:02:08 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:08 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::pushState Jun 09 16:02:08 volumio volumio[935]: info: CoreStateMachine::getState Jun 09 16:02:08 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:08 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:08 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:08 volumio volumio[935]: info: ------------------------------ 538ms Jun 09 16:02:10 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:02:10 volumio volumio[935]: info: CURURI: music-library/NAS/CD/[EAC]機動戦士ガンダムSEED DESTINY ORIGINAL SOUNDTRACK IV(WAV+CUE+LOG) Jun 09 16:02:13 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:02:13 volumio volumio[935]: info: CURURI: music-library/NAS/CD Jun 09 16:02:14 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 09 16:02:14 volumio volumio[935]: info: CURURI: music-library/NAS/CD/[EAC]交響組曲 機動戦士ガンダムSEED DESTINY(WAV+CUE+LOG+BK) Jun 09 16:02:17 volumio volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 09 16:02:17 volumio volumio[935]: info: CoreStateMachine::ClearQueue Jun 09 16:02:17 volumio volumio[935]: info: CoreStateMachine::stop Jun 09 16:02:17 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:02:17 volumio volumio[935]: UNSET VOLATILE Jun 09 16:02:17 volumio volumio[935]: info: CoreStateMachine::stPlaybackTimer Jun 09 16:02:17 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Jun 09 16:02:18 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Jun 09 16:02:18 volumio volumio[935]: info: CoreStateMachine::pushState Jun 09 16:02:18 volumio volumio[935]: info: CoreStateMachine::getState Jun 09 16:02:18 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:18 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:18 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:18 volumio volumio[935]: info: CoreStateMachine::serviceStop Jun 09 16:02:18 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:18 volumio volumio[935]: info: CoreCommandRouter::serviceStop Jun 09 16:02:18 volumio volumio[935]: info: ControllerMpd::stop Jun 09 16:02:18 volumio volumio[935]: info: ControllerMpd::sendMpdCommand stop Jun 09 16:02:18 volumio volumio[935]: info: CorePlayQueue::clearPlayQueue Jun 09 16:02:18 volumio volumio[935]: info: CorePlayQueue::saveQueue Jun 09 16:02:18 volumio volumio[935]: info: CoreStateMachine::pushEmptyState Jun 09 16:02:18 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:18 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:18 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:02:18 volumio volumio[935]: info: CoreStateMachine::addQueueItems Jun 09 16:02:18 volumio volumio[935]: info: CorePlayQueue::addQueueItems Jun 09 16:02:18 volumio volumio[935]: info: Adding Item to queue: music-library/NAS/CD/[EAC]交響組曲 機動戦士ガンダムSEED DESTINY(WAV+CUE+LOG+BK)/CDImage.cue Jun 09 16:02:18 volumio volumio[935]: info: Exploding uri music-library/NAS/CD/[EAC]交響組曲 機動戦士ガンダムSEED DESTINY(WAV+CUE+LOG+BK)/CDImage.cue in service mpd Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: CorePlayQueue::saveQueue Jun 09 16:02:22 volumio volumio[935]: info: CoreCommandRouter::volumioPushQueue Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::updateTrackBlock Jun 09 16:02:22 volumio volumio[935]: info: CorePlayQueue::getTrackBlock Jun 09 16:02:22 volumio volumio[935]: info: CoreCommandRouter::volumioPlay Jun 09 16:02:22 volumio volumio[935]: UNSET VOLATILE Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::play index 0 Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::stop Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:02:22 volumio volumio[935]: UNSET VOLATILE Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::play index undefined Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:02:22 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:22 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Jun 09 16:02:22 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::sendMpdCommand stop Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: Jun 09 16:02:22 volumio volumio[935]: ---------------------------- MPD announces state update: player Jun 09 16:02:22 volumio volumio[935]: info: parsing response... Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::getState Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::sendMpdCommand status Jun 09 16:02:22 volumio volumio[935]: info: parsing response... Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::sendMpdCommand clear Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: Jun 09 16:02:22 volumio volumio[935]: ---------------------------- MPD announces system playlist update Jun 09 16:02:22 volumio volumio[935]: info: Ignoring MPD Status Update Jun 09 16:02:22 volumio volumio[935]: info: parsing response... Jun 09 16:02:22 volumio volumio[935]: info: parsing response... Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::parseState Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::sendMpdCommand load "NAS/CD/[EAC]交響組曲 機動戦士ガンダムSEED DESTINY(WAV+CUE+LOG+BK)/CDImage.cue" Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: null Jun 09 16:02:22 volumio volumio[935]: info: ------------------------------ 78ms Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::pushError Jun 09 16:02:22 volumio volumio[935]: info: [2@0] {} Bad song index Jun 09 16:02:22 volumio volumio[935]: info: ------------------------------ 176ms Jun 09 16:02:22 volumio volumio[935]: info: Jun 09 16:02:22 volumio volumio[935]: ---------------------------- MPD announces system playlist update Jun 09 16:02:22 volumio volumio[935]: info: Ignoring MPD Status Update Jun 09 16:02:22 volumio volumio[935]: info: parsing response... Jun 09 16:02:22 volumio volumio[935]: info: ControllerMpd::sendMpdCommand play Jun 09 16:02:22 volumio volumio[935]: info: sending command... Jun 09 16:02:22 volumio volumio[935]: info: ------------------------------ 82ms Jun 09 16:02:23 volumio volumio[935]: info: parsing response... Jun 09 16:02:23 volumio volumio[935]: info: Jun 09 16:02:23 volumio volumio[935]: ---------------------------- MPD announces state update: player Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::getState Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::sendMpdCommand status Jun 09 16:02:23 volumio volumio[935]: info: sending command... Jun 09 16:02:23 volumio volumio[935]: info: Jun 09 16:02:23 volumio volumio[935]: ---------------------------- MPD announces state update: player Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::getState Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::sendMpdCommand status Jun 09 16:02:23 volumio volumio[935]: info: sending command... Jun 09 16:02:23 volumio volumio[935]: info: parsing response... Jun 09 16:02:23 volumio volumio[935]: info: parsing response... Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::parseState Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::parseState Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:02:23 volumio volumio[935]: info: sending command... Jun 09 16:02:23 volumio volumio[935]: info: sending command... Jun 09 16:02:23 volumio volumio[935]: info: parsing response... Jun 09 16:02:23 volumio volumio[935]: info: parsing response... Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::parseTrackInfo Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::parseTrackInfo Jun 09 16:02:23 volumio volumio[935]: info: ControllerMpd::pushState Jun 09 16:02:23 volumio volumio[935]: info: CoreCommandRouter::servicePushState Jun 09 16:02:23 volumio volumio[935]: info: CoreStateMachine::syncState Jun 09 16:02:23 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:23 volumio volumio[935]: info: STATE SERVICE {"status":"play","position":0,"seek":371,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Opening - 運命の扉開かれる刻","artist":null,"album":"交響組曲 機動戦士ガンダムSEED DESTINY","uri":"NAS/CD/[EAC]交響組曲 機動戦士ガンダムSEED DESTINY(WAV+CUE+LOG+BK)/CDImage.wav","trackType":"wav"} Jun 09 16:02:23 volumio volumio[935]: info: CURRENT POSITION 0 Jun 09 16:02:23 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Jun 09 16:02:23 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:02:23 volumio volumio[935]: info: CoreStateMachine::pushState Jun 09 16:02:23 volumio volumio[935]: info: CoreStateMachine::getState Jun 09 16:02:23 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:23 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:23 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:24 volumio volumio[935]: info: ControllerMpd::pushState Jun 09 16:02:24 volumio volumio[935]: info: CoreCommandRouter::servicePushState Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::syncState Jun 09 16:02:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:24 volumio volumio[935]: info: STATE SERVICE {"status":"play","position":0,"seek":441,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Opening - 運命の扉開かれる刻","artist":null,"album":"交響組曲 機動戦士ガンダムSEED DESTINY","uri":"NAS/CD/[EAC]交響組曲 機動戦士ガンダムSEED DESTINY(WAV+CUE+LOG+BK)/CDImage.wav","trackType":"wav"} Jun 09 16:02:24 volumio volumio[935]: info: CURRENT POSITION 0 Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:02:24 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::pushState Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::getState Jun 09 16:02:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:24 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::pushState Jun 09 16:02:24 volumio volumio[935]: info: CoreStateMachine::getState Jun 09 16:02:24 volumio volumio[935]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:24 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Jun 09 16:02:24 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:02:24 volumio volumio[935]: info: ------------------------------ 1175ms Jun 09 16:02:24 volumio volumio[935]: info: ------------------------------ 1004ms Jun 09 16:02:33 volumio volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 16:02:33 volumio volumio[935]: { Error: connect ETIMEDOUT 108.160.169.178:80 Jun 09 16:02:33 volumio volumio[935]: at Object._errnoException (util.js:1022:11) Jun 09 16:02:33 volumio volumio[935]: at _exceptionWithHostPort (util.js:1044:20) Jun 09 16:02:33 volumio volumio[935]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jun 09 16:02:33 volumio volumio[935]: code: 'ETIMEDOUT', Jun 09 16:02:33 volumio volumio[935]: errno: 'ETIMEDOUT', Jun 09 16:02:33 volumio volumio[935]: syscall: 'connect', Jun 09 16:02:33 volumio volumio[935]: address: '108.160.169.178', Jun 09 16:02:33 volumio volumio[935]: port: 80 } Jun 09 16:02:33 volumio volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 16:02:42 volumio sudo[8996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-09 16:01 Jun 09 16:02:42 volumio sudo[8996]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="ac2a0afddd424ea15d982b8c75eeec3a8648a313" VOLUMIO_FE_VERSION="700492006632ef61a5a17e6a3be4f37c790f883c" VOLUMIO_BE_VERSION="b2e85b2e8facd2d741f2fc40be426a34804b56fc" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue May 5 18:38:33 CEST 2020" VOLUMIO_VERSION="2.773" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="9e13d1d50d1f834c8c0294a226468074"