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"