Dec 23 00:13:13 volumio5 volumio[1124]: info: Preload queue cleared Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::ClearQueue Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::stop Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::serviceStop Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::serviceStop Dec 23 00:13:13 volumio5 volumio[1124]: info: ControllerMpd::stop Dec 23 00:13:13 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::clearPlayQueue Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::saveQueue Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::addQueueItems Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::addQueueItems Dec 23 00:13:13 volumio5 volumio[1124]: info: Preload queue cleared Dec 23 00:13:13 volumio5 volumio[1124]: info: Adding Item to queue: albums://ZARD/forever%20you Dec 23 00:13:13 volumio5 volumio[1124]: info: Exploding uri albums://ZARD/forever%20you in service mpd Dec 23 00:13:13 volumio5 volumio[1124]: info: Dec 23 00:13:13 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:13 volumio5 volumio[1124]: info: sendMpdCommand stop took 103 milliseconds Dec 23 00:13:13 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:13 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::saveQueue Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:13:13 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPlay Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::play index 0 Dec 23 00:13:13 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::play index undefined Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::clearAddPlayTracks USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 154 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand stop took 57 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:13:14 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand clear took 4 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 3 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 2 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac" Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ハピネス","artist":"AI","album":"THE BEST - DELUXE EDITION","uri":"USB/ボリューム/Music/AI/THE BEST/01-ハピネス.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: No code Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: No code Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: No code Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 455ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 300ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 304ms Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:13:14 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:13:14 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:13:14 volumio5 volumio[1124]: error: updateQueue error: null Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:13:14 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 358ms Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand add "USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac" took 356 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 46ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 25ms Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:13:14 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:13:14 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 201ms Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand play took 201 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 191ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 184ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 5 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 4 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 3 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: Dec 23 00:13:14 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 88 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 30 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand status took 24 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:13:14 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:13:14 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 180ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 212ms Dec 23 00:13:14 volumio5 volumio[1124]: info: ------------------------------ 225ms Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:13:14 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1054,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"917 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:13:14 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1054,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"917 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:13:14 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:13:14 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:13:14 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:13:14 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:13:15 volumio5 volumio[1124]: info: ------------------------------ 358ms Dec 23 00:13:15 volumio5 volumio[1124]: info: ------------------------------ 319ms Dec 23 00:13:15 volumio5 volumio[1124]: info: ------------------------------ 314ms Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 00:13:22 volumio5 volumio[1124]: info: Getting Alsa Cards List without I2S DAC Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 00:13:22 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 00:14:00 volumio5 volumio[1124]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Dec 23 00:14:00 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Dec 23 00:14:00 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Dec 23 00:14:00 volumio5 sudo[1959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 00:14:00 volumio5 sudo[1959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 00:14:00 volumio5 sudo[1959]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:00 volumio5 volumio[1124]: info: MPD Permissions set Dec 23 00:14:01 volumio5 sudo[1961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 00:14:01 volumio5 sudo[1961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 00:14:01 volumio5 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 00:14:01 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:01 volumio5 systemd[1]: mpd.service: Deactivated successfully. Dec 23 00:14:01 volumio5 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 23 00:14:01 volumio5 systemd[1]: mpd.service: Consumed 4.269s CPU time. Dec 23 00:14:01 volumio5 systemd[1]: mpd.socket: Deactivated successfully. Dec 23 00:14:01 volumio5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 23 00:14:01 volumio5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 23 00:14:01 volumio5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 00:14:01 volumio5 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 00:14:01 volumio5 sudo[1970]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 00:14:01 volumio5 sudo[1970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 00:14:01 volumio5 sudo[1970]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:03 volumio5 mpd[1972]: 2025-12-23T00:14:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 00:14:03 volumio5 systemd[1]: Started mpd.service - Music Player Daemon. Dec 23 00:14:03 volumio5 sudo[1961]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:03 volumio5 volumio[1124]: error: updateQueue error: null Dec 23 00:14:06 volumio5 sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 00:14:06 volumio5 sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 00:14:06 volumio5 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 23 00:14:06 volumio5 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 23 00:14:06 volumio5 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 23 00:14:06 volumio5 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 00:14:06 volumio5 mpd_monitor.sh[1992]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 00:14:06 volumio5 sudo[1990]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:06 volumio5 volumio[1124]: info: Successfully started MPD Monitor Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::volumioNext Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::next Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::stop Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::serviceStop Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::serviceStop Dec 23 00:14:07 volumio5 volumio[1124]: info: ControllerMpd::stop Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand stop took 75 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::play index undefined Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::clearAddPlayTracks USB/ボリューム/Music/ZARD/forever you/02-ハイヒール脱ぎ捨てて.flac Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand stop took 3 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 00:14:07 volumio5 volumio[1124]: info: Dec 23 00:14:07 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:14:07 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand clear took 46 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "USB/ボリューム/Music/ZARD/forever you/02-ハイヒール脱ぎ捨てて.flac" Dec 23 00:14:07 volumio5 volumio[1124]: error: updateQueue error: null Dec 23 00:14:07 volumio5 volumio[1124]: info: Dec 23 00:14:07 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:14:07 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:14:07 volumio5 volumio[1124]: info: ------------------------------ 29ms Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand add "USB/ボリューム/Music/ZARD/forever you/02-ハイヒール脱ぎ捨てて.flac" took 28 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Dec 23 00:14:07 volumio5 volumio[1124]: info: ------------------------------ 26ms Dec 23 00:14:07 volumio5 volumio[1124]: info: Dec 23 00:14:07 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand play took 146 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:07 volumio5 volumio[1124]: info: Dec 23 00:14:07 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:07 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand status took 21 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand status took 16 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:07 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:07 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1064 Kbps","isStreaming":false,"title":"ハイヒール脱ぎ捨てて","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/02-ハイヒール脱ぎ捨てて.flac","trackType":"flac"} Dec 23 00:14:07 volumio5 volumio[1124]: verbose: CURRENT POSITION 1 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:14:07 volumio5 volumio[1124]: info: ------------------------------ 38ms Dec 23 00:14:07 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 00:14:07 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:07 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:07 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1066 Kbps","isStreaming":false,"title":"ハイヒール脱ぎ捨てて","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/02-ハイヒール脱ぎ捨てて.flac","trackType":"flac"} Dec 23 00:14:07 volumio5 volumio[1124]: verbose: CURRENT POSITION 1 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:14:07 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:07 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:07 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:07 volumio5 volumio[1124]: info: ------------------------------ 125ms Dec 23 00:14:20 volumio5 volumio[1124]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Dec 23 00:14:20 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Dec 23 00:14:20 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Dec 23 00:14:20 volumio5 sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 00:14:21 volumio5 sudo[2018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 00:14:21 volumio5 sudo[2018]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 sudo[2020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 00:14:21 volumio5 sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 00:14:21 volumio5 volumio[1124]: info: MPD Permissions set Dec 23 00:14:21 volumio5 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 23 00:14:21 volumio5 systemd[1]: mpd.service: Deactivated successfully. Dec 23 00:14:21 volumio5 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 23 00:14:21 volumio5 systemd[1]: mpd.service: Consumed 1.781s CPU time. Dec 23 00:14:21 volumio5 systemd[1]: mpd.socket: Deactivated successfully. Dec 23 00:14:21 volumio5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 23 00:14:21 volumio5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 23 00:14:21 volumio5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 00:14:21 volumio5 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 00:14:21 volumio5 sudo[2029]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 00:14:21 volumio5 sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 00:14:21 volumio5 sudo[2029]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:23 volumio5 mpd[2031]: 2025-12-23T00:14:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 00:14:23 volumio5 systemd[1]: Started mpd.service - Music Player Daemon. Dec 23 00:14:23 volumio5 sudo[2020]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:23 volumio5 volumio[1124]: error: updateQueue error: null Dec 23 00:14:24 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPrevious Dec 23 00:14:24 volumio5 volumio[1124]: info: CoreStateMachine::previous Dec 23 00:14:24 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:24 volumio5 volumio[1124]: info: ControllerMpd::seek Dec 23 00:14:24 volumio5 volumio[1124]: error: [2@0] {seek} Bad song index Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPrevious Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::previous Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::stop Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::serviceStop Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 1 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::serviceStop Dec 23 00:14:26 volumio5 volumio[1124]: info: ControllerMpd::stop Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand stop took 5 milliseconds Dec 23 00:14:26 volumio5 sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 00:14:26 volumio5 sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 00:14:26 volumio5 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 23 00:14:26 volumio5 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 23 00:14:26 volumio5 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 23 00:14:26 volumio5 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 00:14:26 volumio5 sudo[2049]: pam_unix(sudo:session): session closed for user root Dec 23 00:14:26 volumio5 mpd_monitor.sh[2051]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 00:14:26 volumio5 volumio[1124]: info: Successfully started MPD Monitor Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::play index undefined Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::clearAddPlayTracks USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 00:14:26 volumio5 volumio[1124]: info: Dec 23 00:14:26 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:14:26 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand clear took 2 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac" Dec 23 00:14:26 volumio5 volumio[1124]: error: updateQueue error: null Dec 23 00:14:26 volumio5 volumio[1124]: info: Dec 23 00:14:26 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:14:26 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:14:26 volumio5 volumio[1124]: info: ------------------------------ 14ms Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand add "USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac" took 10 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Dec 23 00:14:26 volumio5 volumio[1124]: info: ------------------------------ 24ms Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand play took 25 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: info: Dec 23 00:14:26 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:26 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:26 volumio5 volumio[1124]: info: Dec 23 00:14:26 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand status took 32 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand status took 10 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:26 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:26 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:14:26 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:14:26 volumio5 volumio[1124]: info: ------------------------------ 68ms Dec 23 00:14:26 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 23 00:14:26 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:26 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:26 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:14:26 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:14:26 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:26 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:26 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:26 volumio5 volumio[1124]: info: ------------------------------ 96ms Dec 23 00:14:40 volumio5 volumio[1124]: info: Preload queue cleared Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::ClearQueue Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::stop Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::serviceStop Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::serviceStop Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::stop Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::clearPlayQueue Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::saveQueue Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::addQueueItems Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::addQueueItems Dec 23 00:14:40 volumio5 volumio[1124]: info: Preload queue cleared Dec 23 00:14:40 volumio5 volumio[1124]: info: Adding Item to queue: albums://%E3%82%A2%E3%83%B3%E3%83%BB%E3%83%AB%E3%82%A4%E3%82%B9/%E3%82%A2%E3%83%B3%E3%83%BB%E3%83%AB%E3%82%A4%E3%82%B9%E3%83%BB%E3%82%B0%E3%83%AC%E3%82%A4%E3%83%86%E3%82%B9%E3%83%88%E3%83%BB%E3%83%92%E3%83%83%E3%83%84%E3%83%BB%E3%82%A6%E3%82%A3%E3%82%BA%E3%83%BB%E3%82%AB%E3%83%B4%E3%82%A1%E3%83%BC%E3%82%BA Dec 23 00:14:40 volumio5 volumio[1124]: info: Exploding uri albums://%E3%82%A2%E3%83%B3%E3%83%BB%E3%83%AB%E3%82%A4%E3%82%B9/%E3%82%A2%E3%83%B3%E3%83%BB%E3%83%AB%E3%82%A4%E3%82%B9%E3%83%BB%E3%82%B0%E3%83%AC%E3%82%A4%E3%83%86%E3%82%B9%E3%83%88%E3%83%BB%E3%83%92%E3%83%83%E3%83%84%E3%83%BB%E3%82%A6%E3%82%A3%E3%82%BA%E3%83%BB%E3%82%AB%E3%83%B4%E3%82%A1%E3%83%BC%E3%82%BA in service mpd Dec 23 00:14:40 volumio5 volumio[1124]: info: Dec 23 00:14:40 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand stop took 46 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::saveQueue Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::updateTrackBlock Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrackBlock Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPlay Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::play index 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::stop Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::play index undefined Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::clearAddPlayTracks USB/ボリューム/Music/アン・ルイス/アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ [Disc 1]/01-六本木心中.flac Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand status took 107 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand stop took 30 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 00:14:40 volumio5 volumio[1124]: info: Dec 23 00:14:40 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:14:40 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand clear took 20 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand add "USB/ボリューム/Music/アン・ルイス/アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ [Disc 1]/01-六本木心中.flac" Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"今すぐ会いに来て","artist":"ZARD","album":"forever you","uri":"USB/ボリューム/Music/ZARD/forever you/01-今すぐ会いに来て.flac","trackType":"flac"} Dec 23 00:14:40 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService stop Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:40 volumio5 volumio[1124]: info: No code Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:40 volumio5 volumio[1124]: info: ------------------------------ 224ms Dec 23 00:14:40 volumio5 volumio[1124]: error: updateQueue error: null Dec 23 00:14:40 volumio5 volumio[1124]: info: Dec 23 00:14:40 volumio5 volumio[1124]: ---------------------------- MPD announces system playlist update Dec 23 00:14:40 volumio5 volumio[1124]: info: Ignoring MPD Status Update Dec 23 00:14:40 volumio5 volumio[1124]: info: ------------------------------ 140ms Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand add "USB/ボリューム/Music/アン・ルイス/アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ [Disc 1]/01-六本木心中.flac" took 129 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Dec 23 00:14:40 volumio5 volumio[1124]: info: Dec 23 00:14:40 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:40 volumio5 volumio[1124]: info: ------------------------------ 51ms Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand play took 66 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:40 volumio5 volumio[1124]: info: Dec 23 00:14:40 volumio5 volumio[1124]: ---------------------------- MPD announces state update: player Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::getState Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand status took 20 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand status took 10 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::parseState Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"六本木心中","artist":"アン・ルイス","album":"アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ","uri":"USB/ボリューム/Music/アン・ルイス/アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ [Disc 1]/01-六本木心中.flac","trackType":"flac"} Dec 23 00:14:40 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:14:40 volumio5 volumio[1124]: info: ------------------------------ 100ms Dec 23 00:14:40 volumio5 volumio[1124]: info: sendMpdCommand playlistinfo took 73 milliseconds Dec 23 00:14:40 volumio5 volumio[1124]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:14:40 volumio5 volumio[1124]: info: ControllerMpd::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::servicePushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"六本木心中","artist":"アン・ルイス","album":"アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ","uri":"USB/ボリューム/Music/アン・ルイス/アン・ルイス・グレイテスト・ヒッツ・ウィズ・カヴァーズ [Disc 1]/01-六本木心中.flac","trackType":"flac"} Dec 23 00:14:40 volumio5 volumio[1124]: verbose: CURRENT POSITION 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::syncState stateService play Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:14:40 volumio5 volumio[1124]: info: Received an update from plugin. extracting info from payload Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreStateMachine::pushState Dec 23 00:14:40 volumio5 volumio[1124]: info: CorePlayQueue::getTrack 0 Dec 23 00:14:40 volumio5 volumio[1124]: info: CoreCommandRouter::volumioPushState Dec 23 00:14:40 volumio5 volumio[1124]: info: ------------------------------ 219ms Dec 23 00:14:44 volumio5 volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 23 00:14:44 volumio5 volumio[1124]: info: CURURI: artists:// Dec 23 00:14:44 volumio5 volumio[1124]: info: Preload queue cleared Dec 23 00:14:46 volumio5 volumio[1124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 00:14:46 volumio5 volumio[1124]: TypeError: Cannot read properties of null (reading 'slice') Dec 23 00:14:46 volumio5 volumio[1124]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 23 00:14:46 volumio5 volumio[1124]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 23 00:14:46 volumio5 volumio[1124]: at Object.onceWrapper (node:events:629:26) Dec 23 00:14:46 volumio5 volumio[1124]: at ClientRequest.emit (node:events:514:28) Dec 23 00:14:46 volumio5 volumio[1124]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Dec 23 00:14:46 volumio5 volumio[1124]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Dec 23 00:14:46 volumio5 volumio[1124]: at TLSSocket.socketOnData (node:_http_client:535:22) Dec 23 00:14:46 volumio5 volumio[1124]: at TLSSocket.emit (node:events:514:28) Dec 23 00:14:46 volumio5 volumio[1124]: at addChunk (node:internal/streams/readable:343:12) Dec 23 00:14:46 volumio5 volumio[1124]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 23 00:14:46 volumio5 volumio[1124]: at Readable.push (node:internal/streams/readable:253:10) Dec 23 00:14:46 volumio5 volumio[1124]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Dec 23 00:14:46 volumio5 volumio[1124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 00:14:49 volumio5 sudo[2120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 00:13' Dec 23 00:14:50 volumio5 sudo[2120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"