Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::volumioPause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::servicePause Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::servicePause Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::pause Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand pause Jan 12 17:47:03 volumio volumio[625316]: info: Jan 12 17:47:03 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand pause took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:03 volumio volumio[625316]: info: Jan 12 17:47:03 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:03 volumio volumio[625316]: info: Jan 12 17:47:03 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:03 volumio volumio[625316]: info: Jan 12 17:47:03 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:47:03 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:03 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32492,"duration":183,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"08. ????????ʱ??","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/08. 被遗忘的时光.wav","trackType":"wav"} Jan 12 17:47:03 volumio volumio[625316]: verbose: CURRENT POSITION 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState stateService pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32492,"duration":183,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"08. ????????ʱ??","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/08. 被遗忘的时光.wav","trackType":"wav"} Jan 12 17:47:03 volumio volumio[625316]: verbose: CURRENT POSITION 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState stateService pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32492,"duration":183,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"08. ????????ʱ??","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/08. 被遗忘的时光.wav","trackType":"wav"} Jan 12 17:47:03 volumio volumio[625316]: verbose: CURRENT POSITION 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState stateService pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:03 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32492,"duration":183,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"08. ????????ʱ??","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/08. 被遗忘的时光.wav","trackType":"wav"} Jan 12 17:47:03 volumio volumio[625316]: verbose: CURRENT POSITION 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState stateService pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus pause Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:03 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:03 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:03 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:03 volumio volumio[625316]: info: ------------------------------ 53ms Jan 12 17:47:03 volumio volumio[625316]: info: ------------------------------ 51ms Jan 12 17:47:03 volumio volumio[625316]: info: ------------------------------ 51ms Jan 12 17:47:03 volumio volumio[625316]: info: ------------------------------ 50ms Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:03 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:03 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:03 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:03 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:03 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:05 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 17:47:05 volumio volumio[625316]: info: CURURI: artists://%3F%3F%CA%AF%3F%3F%3F%3F Jan 12 17:47:05 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:05 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:05 volumio volumio[625316]: info: Preloading song: music-library/NAS/4/music/裸足でSummer/オフショアガール.wav Jan 12 17:47:05 volumio volumio[625316]: info: Preloading song: music-library/NAS/4/music/裸足でSummer/オフショアガール～off vocal ver.～.wav Jan 12 17:47:05 volumio volumio[625316]: info: Exploding uri music-library/NAS/4/music/裸足でSummer/オフショアガール.wav in service mpd Jan 12 17:47:05 volumio volumio[625316]: info: ALBUMART /albumart?cacheid=807&web=%3F%3F%CA%AF%3F%3F%3F%3F/%3F%3F%3F%3F%3F%3FSummer/extralarge&path=%2Fmnt%2FNAS%2F4%2Fmusic%2F%E8%A3%B8%E8%B6%B3%E3%81%A7Summer%2F%E3%82%AA%E3%83%95%E3%82%B7%E3%83%A7%E3%82%A2%E3%82%AC%E3%83%BC%E3%83%AB.wav&metadata=false Jan 12 17:47:05 volumio volumio[625316]: info: URI /mnt/NAS/4/music/裸足でSummer/オフショアガール.wav Jan 12 17:47:05 volumio volumio[625316]: info: Exploding uri music-library/NAS/4/music/裸足でSummer/オフショアガール～off vocal ver.～.wav in service mpd Jan 12 17:47:05 volumio volumio[625316]: info: ALBUMART /albumart?cacheid=807&web=%3F%3F%CA%AF%3F%3F%3F%3F/%3F%3F%3F%3F%3F%3FSummer/extralarge&path=%2Fmnt%2FNAS%2F4%2Fmusic%2F%E8%A3%B8%E8%B6%B3%E3%81%A7Summer%2F%E3%82%AA%E3%83%95%E3%82%B7%E3%83%A7%E3%82%A2%E3%82%AC%E3%83%BC%E3%83%AB%EF%BD%9Eoff%20vocal%20ver.%EF%BD%9E.wav&metadata=false Jan 12 17:47:05 volumio volumio[625316]: info: URI /mnt/NAS/4/music/裸足でSummer/オフショアガール～off vocal ver.～.wav Jan 12 17:47:06 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::ClearQueue Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::serviceStop Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 7 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::serviceStop Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::stop Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::clearPlayQueue Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::addQueueItems Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::addQueueItems Jan 12 17:47:06 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:06 volumio volumio[625316]: info: Adding Item to queue: artists://%3F%3F%CA%AF%3F%3F%3F%3F Jan 12 17:47:06 volumio volumio[625316]: info: Exploding uri artists://%3F%3F%CA%AF%3F%3F%3F%3F in service mpd Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand stop took 19 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPlay Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::play index 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::play index undefined Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::clearAddPlayTracks NAS/4/music/裸足でSummer/オフショアガール.wav Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 12 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand stop took 3 milliseconds Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand clear took 5 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand add "NAS/4/music/裸足でSummer/オフショアガール.wav" Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: 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":"08. ????????ʱ??","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/08. 被遗忘的时光.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: No code Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: 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} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: No code Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: 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} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: No code Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: 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} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: No code Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 128ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 114ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 114ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 114ms Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: error: updateQueue error: null Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 119ms Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand add "NAS/4/music/裸足でSummer/オフショアガール.wav" took 117 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 5ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 4ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 3ms Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:06 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 65ms Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand play took 65 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 64ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 63ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 62ms Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 12 17:47:06 volumio volumio[625316]: info: camilladsp stopping service pid 626127... Jan 12 17:47:06 volumio volumio[625316]: info: camilladsp service terminated, instance 1 Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 17:47:06 volumio volumio[625316]: info: camilladsp service started and running in background, instance 1 Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 54 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 53 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 52 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 44 milliseconds Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: Jan 12 17:47:06 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 27 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 26 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 26 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 26 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 25 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 24 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand status took 23 milliseconds Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":296,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 112ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 157ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 157ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 156ms Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 83 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 83 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 83 milliseconds Jan 12 17:47:06 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 82 milliseconds Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:06 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:06 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:06 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:06 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 208ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 204ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 204ms Jan 12 17:47:06 volumio volumio[625316]: info: ------------------------------ 203ms Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:06 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:07 volumio go-librespot[625517]: time="2026-01-12T17:47:07+08:00" level=trace msg="sent dealer ping" Jan 12 17:47:07 volumio go-librespot[625517]: time="2026-01-12T17:47:07+08:00" level=trace msg="received dealer pong" Jan 12 17:47:08 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::ClearQueue Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::serviceStop Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::serviceStop Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::stop Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::clearPlayQueue Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::addQueueItems Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::addQueueItems Jan 12 17:47:08 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:08 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/裸足でSummer/オフショアガール.wav Jan 12 17:47:08 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/裸足でSummer/オフショアガール.wav Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPlay Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::play index 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::addQueueItems Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::addQueueItems Jan 12 17:47:08 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:08 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/裸足でSummer/オフショアガール～off vocal ver.～.wav Jan 12 17:47:08 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/裸足でSummer/オフショアガール～off vocal ver.～.wav Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::play index undefined Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::clearAddPlayTracks NAS/4/music/裸足でSummer/オフショアガール.wav Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand stop took 43 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand stop took 11 milliseconds Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand clear took 4 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand add "NAS/4/music/裸足でSummer/オフショアガール.wav" Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: 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} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: No code Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 41ms Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: error: updateQueue error: null Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 48 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 47 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 47ms Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 45 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand add "NAS/4/music/裸足でSummer/オフショアガール.wav" took 45 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 8ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 7ms Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: 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} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: No code Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: 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} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: No code Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 110ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 109ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 113ms Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 109ms Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand play took 103 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 42ms Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 41ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 40ms Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:08 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:08 volumio volumio[625316]: info: Jan 12 17:47:08 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 17 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 18 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 16 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 16 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 16ms Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand status took 15 milliseconds Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 24ms Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 17:47:08 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:08 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:08 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:08 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:08 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"???ե??祢???`??","artist":"??ʯ????","album":"??????Summer","uri":"NAS/4/music/裸足でSummer/オフショアガール.wav","trackType":"wav"} Jan 12 17:47:08 volumio volumio[625316]: verbose: CURRENT POSITION 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:08 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:08 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:08 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 91ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 90ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 90ms Jan 12 17:47:08 volumio volumio[625316]: info: ------------------------------ 90ms Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:08 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:13 volumio volumio[625316]: info: VolumeController::SetAlsaVolume45 Jan 12 17:47:13 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:13 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:13 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:13 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:13 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:13 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 12 17:47:13 volumio volumio[625316]: SPOTIFY: SPOTIFY VOLUME 55 Jan 12 17:47:13 volumio volumio[625316]: SPOTIFY: VOLUMIO VOLUME 45 Jan 12 17:47:13 volumio volumio[625316]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 12 17:47:13 volumio volumio[625316]: info: Setting Spotify Volume from Volumio: 45 Jan 12 17:47:14 volumio volumio[625316]: info: VolumeController::SetAlsaVolume35 Jan 12 17:47:14 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:14 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:14 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:14 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:14 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:14 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:14 volumio volumio[625316]: SPOTIFY: SPOTIFY VOLUME 45 Jan 12 17:47:14 volumio volumio[625316]: SPOTIFY: VOLUMIO VOLUME 35 Jan 12 17:47:14 volumio volumio[625316]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 12 17:47:14 volumio volumio[625316]: info: Setting Spotify Volume from Volumio: 35 Jan 12 17:47:15 volumio volumio[625316]: SPOTIFY: SETTING SPOTIFY VOLUME 35 Jan 12 17:47:15 volumio volumio[625316]: info: Sending Spotify command with payload to local API: /player/volume Jan 12 17:47:15 volumio go-librespot[625517]: time="2026-01-12T17:47:15+08:00" level=debug msg="update volume requested to 22937/65535" Jan 12 17:47:16 volumio go-librespot[625517]: time="2026-01-12T17:47:16+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 12 17:47:16 volumio go-librespot[625517]: time="2026-01-12T17:47:16+08:00" level=trace msg="emitting websocket event: volume" Jan 12 17:47:16 volumio volumio[625316]: SPOTIFY: received: {"type":"volume","data":{"value":35,"max":100}} Jan 12 17:47:16 volumio volumio[625316]: SPOTIFY: RECEIVED SPOTIFY VOLUME 35 Jan 12 17:47:24 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 17:47:24 volumio volumio[625316]: info: CURURI: artists://%3F%3F%3F%3F Jan 12 17:47:24 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:24 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:25 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::ClearQueue Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::serviceStop Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 0 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::serviceStop Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::stop Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::clearPlayQueue Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::addQueueItems Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::addQueueItems Jan 12 17:47:25 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/01. 出塞曲.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/01. 出塞曲.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/02. 庭院深深.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/02. 庭院深深.wav Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPlay Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::play index 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::addQueueItems Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::addQueueItems Jan 12 17:47:25 volumio volumio[625316]: info: Preload queue cleared Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/03. 怎么能.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/03. 怎么能.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/04. 抉择.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/04. 抉择.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/05. 晨书.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/05. 晨书.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/06. 相思雨.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/06. 相思雨.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/07. 船.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/07. 船.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/08. 被遗忘的时光.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/08. 被遗忘的时光.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/09. 远扬的梦舟.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/09. 远扬的梦舟.wav Jan 12 17:47:25 volumio volumio[625316]: info: Adding Item to queue: music-library/NAS/4/music/出塞曲/10. 送别.wav Jan 12 17:47:25 volumio volumio[625316]: info: Using cached record of: music-library/NAS/4/music/出塞曲/10. 送别.wav Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::saveQueue Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::play index undefined Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::updateTrackBlock Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrackBlock Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::clearAddPlayTracks NAS/4/music/出塞曲/02. 庭院深深.wav Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand stop took 50 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand stop took 17 milliseconds Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand status took 6 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand clear took 5 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand add "NAS/4/music/出塞曲/02. 庭院深深.wav" Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: 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} Jan 12 17:47:25 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: No code Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: 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} Jan 12 17:47:25 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: No code Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: 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} Jan 12 17:47:25 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::syncState stateService stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: No code Jan 12 17:47:25 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:25 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:25 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 87ms Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 87ms Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 86ms Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: error: updateQueue error: null Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 91ms Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 90 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: sendMpdCommand add "NAS/4/music/出塞曲/02. 庭院深深.wav" took 90 milliseconds Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 7ms Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 6ms Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 5ms Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:25 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:47:25 volumio volumio[625316]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 12 17:47:25 volumio volumio[625316]: info: ------------------------------ 99ms Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: Jan 12 17:47:25 volumio volumio[625316]: ---------------------------- MPD announces system playlist update Jan 12 17:47:25 volumio volumio[625316]: info: Ignoring MPD Status Update Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Volumio is not playing Jan 12 17:47:25 volumio volumio[625316]: info: FusionDsp - Clipped samples monitor stopped Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 50ms Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand play took 48 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 46ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 46ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 45ms Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - ---- read samplerate from file: 96000 Jan 12 17:47:26 volumio volumio[625316]: info: camilladsp stopping service pid 626144... Jan 12 17:47:26 volumio volumio[625316]: grep: /proc/626144/cmdline: binary file matches Jan 12 17:47:26 volumio volumio[625316]: grep: /proc/626144/cmdline: binary file matches Jan 12 17:47:26 volumio volumio[625316]: grep: /proc/626144/cmdline: binary file matches Jan 12 17:47:26 volumio volumio[625316]: grep: /proc/626144/cmdline: binary file matches Jan 12 17:47:26 volumio volumio[625316]: info: camilladsp service terminated, instance 1 Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 17:47:26 volumio volumio[625316]: info: camilladsp service started and running in background, instance 1 Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 517 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 516 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 516 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 514 milliseconds Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: Jan 12 17:47:26 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 26 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 23 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 22 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand status took 22 milliseconds Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 569ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 609ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 608ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 607ms Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 79 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 78 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 78 milliseconds Jan 12 17:47:26 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":864,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:26 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:26 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:26 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:26 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 180ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 177ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 176ms Jan 12 17:47:26 volumio volumio[625316]: info: ------------------------------ 175ms Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:26 volumio volumio[625316]: info: FusionDsp - Clipping Monitor started Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioSeek Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::seek Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: TRACKBLOCK {"uri":"mnt/NAS/4/music/出塞曲/02. 庭院深深.wav","service":"mpd","name":"02. ͥԺ????","artist":"????","album":"??????","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=807&web=%3F%3F%3F%3F/%3F%3F%3F%3F%3F%3F/extralarge&path=%2FNAS%2F4%2Fmusic%2F%E5%87%BA%E5%A1%9E%E6%9B%B2&metadata=false","duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","trackType":"wav","channels":2} Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::seek Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: error: null Jan 12 17:47:28 volumio volumio[625316]: info: Jan 12 17:47:28 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:28 volumio volumio[625316]: info: Jan 12 17:47:28 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:28 volumio volumio[625316]: info: Jan 12 17:47:28 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:28 volumio volumio[625316]: info: Jan 12 17:47:28 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand status took 11 milliseconds Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand status took 10 milliseconds Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand status took 9 milliseconds Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand status took 7 milliseconds Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 12 17:47:28 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:28 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12492,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:28 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:28 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12492,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:28 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:28 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12492,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:28 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:28 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12492,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:28 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:28 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:28 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:28 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:28 volumio volumio[625316]: info: ------------------------------ 98ms Jan 12 17:47:28 volumio volumio[625316]: info: ------------------------------ 97ms Jan 12 17:47:28 volumio volumio[625316]: info: ------------------------------ 95ms Jan 12 17:47:28 volumio volumio[625316]: info: ------------------------------ 95ms Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:28 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 17:47:29 volumio volumio[625316]: info: Retrying Login Due to Network Error Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioSeek Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::seek Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: TRACKBLOCK {"uri":"mnt/NAS/4/music/出塞曲/02. 庭院深深.wav","service":"mpd","name":"02. ͥԺ????","artist":"????","album":"??????","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=807&web=%3F%3F%3F%3F/%3F%3F%3F%3F%3F%3F/extralarge&path=%2FNAS%2F4%2Fmusic%2F%E5%87%BA%E5%A1%9E%E6%9B%B2&metadata=false","duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","trackType":"wav","channels":2} Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::seek Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: error: null Jan 12 17:47:29 volumio volumio[625316]: info: Jan 12 17:47:29 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:29 volumio volumio[625316]: info: Jan 12 17:47:29 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:29 volumio volumio[625316]: info: Jan 12 17:47:29 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:29 volumio volumio[625316]: info: Jan 12 17:47:29 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand status took 7 milliseconds Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand status took 7 milliseconds Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand status took 6 milliseconds Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:29 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:29 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:29 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:29 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: ------------------------------ 54ms Jan 12 17:47:29 volumio volumio[625316]: info: ------------------------------ 54ms Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 47 milliseconds Jan 12 17:47:29 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 47 milliseconds Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:29 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:29 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:29 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:29 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:29 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:29 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:29 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:29 volumio volumio[625316]: info: ------------------------------ 98ms Jan 12 17:47:29 volumio volumio[625316]: info: ------------------------------ 98ms Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:29 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioSeek Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::seek Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: TRACKBLOCK {"uri":"mnt/NAS/4/music/出塞曲/02. 庭院深深.wav","service":"mpd","name":"02. ͥԺ????","artist":"????","album":"??????","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=807&web=%3F%3F%3F%3F/%3F%3F%3F%3F%3F%3F/extralarge&path=%2FNAS%2F4%2Fmusic%2F%E5%87%BA%E5%A1%9E%E6%9B%B2&metadata=false","duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","trackType":"wav","channels":2} Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::seek Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: error: null Jan 12 17:47:30 volumio volumio[625316]: info: Jan 12 17:47:30 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:30 volumio volumio[625316]: info: Jan 12 17:47:30 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:30 volumio volumio[625316]: info: Jan 12 17:47:30 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:30 volumio volumio[625316]: info: Jan 12 17:47:30 volumio volumio[625316]: ---------------------------- MPD announces state update: player Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::getState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand status took 12 milliseconds Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand status took 11 milliseconds Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand status took 11 milliseconds Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand status took 10 milliseconds Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseState Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:47:30 volumio volumio[625316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:30 volumio volumio[625316]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:30 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:30 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:30 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:30 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:30 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:30 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: ControllerMpd::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::servicePushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":191,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4608 Kbps","isStreaming":false,"title":"02. ͥԺ????","artist":"????","album":"??????","uri":"NAS/4/music/出塞曲/02. 庭院深深.wav","trackType":"wav"} Jan 12 17:47:30 volumio volumio[625316]: verbose: CURRENT POSITION 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState stateService play Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:47:30 volumio volumio[625316]: info: Received an update from plugin. extracting info from payload Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:30 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:30 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:30 volumio volumio[625316]: info: ------------------------------ 91ms Jan 12 17:47:30 volumio volumio[625316]: info: ------------------------------ 90ms Jan 12 17:47:30 volumio volumio[625316]: info: ------------------------------ 89ms Jan 12 17:47:30 volumio volumio[625316]: info: ------------------------------ 87ms Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:30 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 12 17:47:34 volumio volumio[625316]: info: VolumeController::SetAlsaVolume40 Jan 12 17:47:34 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:34 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:34 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:34 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:34 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: SPOTIFY VOLUME 35 Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: VOLUMIO VOLUME 40 Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 12 17:47:34 volumio volumio[625316]: info: Setting Spotify Volume from Volumio: 40 Jan 12 17:47:34 volumio volumio[625316]: info: VolumeController::SetAlsaVolume45 Jan 12 17:47:34 volumio volumio[625316]: info: CoreStateMachine::pushState Jan 12 17:47:34 volumio volumio[625316]: info: CorePlayQueue::getTrack 1 Jan 12 17:47:34 volumio volumio[625316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:47:34 volumio volumio[625316]: info: CoreCommandRouter::volumioPushState Jan 12 17:47:34 volumio volumio[625316]: info: FusionDsp - Volumio is playing Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: SPOTIFY VOLUME 40 Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: VOLUMIO VOLUME 45 Jan 12 17:47:34 volumio volumio[625316]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 12 17:47:34 volumio volumio[625316]: info: Setting Spotify Volume from Volumio: 45 Jan 12 17:47:36 volumio volumio[625316]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Jan 12 17:47:36 volumio volumio[625316]: info: Sending Spotify command with payload to local API: /player/volume Jan 12 17:47:36 volumio go-librespot[625517]: time="2026-01-12T17:47:36+08:00" level=debug msg="update volume requested to 29490/65535" Jan 12 17:47:36 volumio go-librespot[625517]: time="2026-01-12T17:47:36+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 12 17:47:36 volumio go-librespot[625517]: time="2026-01-12T17:47:36+08:00" level=trace msg="emitting websocket event: volume" Jan 12 17:47:36 volumio volumio[625316]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Jan 12 17:47:36 volumio volumio[625316]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Jan 12 17:47:37 volumio go-librespot[625517]: time="2026-01-12T17:47:37+08:00" level=trace msg="sent dealer ping" Jan 12 17:47:37 volumio go-librespot[625517]: time="2026-01-12T17:47:37+08:00" level=trace msg="received dealer pong" Jan 12 17:47:39 volumio volumio[625316]: info: MyVolumio login type: Token Jan 12 17:48:07 volumio go-librespot[625517]: time="2026-01-12T17:48:07+08:00" level=trace msg="received accesspoint ping" Jan 12 17:48:07 volumio go-librespot[625517]: time="2026-01-12T17:48:07+08:00" level=trace msg="received accesspoint pong ack" Jan 12 17:48:07 volumio go-librespot[625517]: time="2026-01-12T17:48:07+08:00" level=trace msg="sent dealer ping" Jan 12 17:48:07 volumio go-librespot[625517]: time="2026-01-12T17:48:07+08:00" level=trace msg="received dealer pong" Jan 12 17:48:09 volumio volumio[625316]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 17:48:09 volumio volumio[625316]: info: Retrying Login Due to Network Error Jan 12 17:48:19 volumio volumio[625316]: info: MyVolumio login type: Token Jan 12 17:48:27 volumio volumio[625316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 17:48:27 volumio volumio[625316]: Error: connect ETIMEDOUT 104.244.46.9:443 Jan 12 17:48:27 volumio volumio[625316]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 12 17:48:27 volumio volumio[625316]: errno: -110, Jan 12 17:48:27 volumio volumio[625316]: code: 'ETIMEDOUT', Jan 12 17:48:27 volumio volumio[625316]: syscall: 'connect', Jan 12 17:48:27 volumio volumio[625316]: address: '104.244.46.9', Jan 12 17:48:27 volumio volumio[625316]: port: 443 Jan 12 17:48:27 volumio volumio[625316]: } Jan 12 17:48:27 volumio volumio[625316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 17:48:27 volumio sudo[626605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 17:47' Jan 12 17:48:27 volumio sudo[626605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"