-- Logs begin at Thu 2024-04-25 19:22:27 HKT, end at Thu 2024-04-25 19:31:11 HKT. -- Apr 25 19:30:00 primo volumio[5321]: info: CoreCommandRouter::volumioPlay Apr 25 19:30:00 primo volumio[5321]: info: CoreStateMachine::play index undefined Apr 25 19:30:00 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:00 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:00 primo volumio[5321]: info: CoreStateMachine::startPlaybackTimer Apr 25 19:30:00 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:00 primo volumio[5321]: info: ControllerMpd::resume Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand play Apr 25 19:30:00 primo volumio[5321]: info: Apr 25 19:30:00 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:00 primo volumio[5321]: info: sendMpdCommand play took 10 milliseconds Apr 25 19:30:00 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:00 primo volumio[5321]: info: sendMpdCommand status took 2 milliseconds Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:00 primo volumio[5321]: info: Apr 25 19:30:00 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:00 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:00 primo volumio[5321]: info: Apr 25 19:30:00 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:00 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:00 primo volumio[5321]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 25 19:30:00 primo volumio[5321]: info: sendMpdCommand status took 4 milliseconds Apr 25 19:30:00 primo volumio[5321]: info: sendMpdCommand status took 3 milliseconds Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:00 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:00 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:00 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:00 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:00 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100465,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"686 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:00 primo volumio[5321]: verbose: CURRENT POSITION 7 Apr 25 19:30:00 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:00 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:00 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:00 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:00 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:00 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:01 primo volumio[5321]: info: ------------------------------ 29ms Apr 25 19:30:01 primo volumio[5321]: error: Upnp client error: Error: This socket has been ended by the other party Apr 25 19:30:01 primo volumio[5321]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 25 19:30:01 primo volumio[5321]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 25 19:30:01 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:01 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:01 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:01 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100465,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"686 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:01 primo volumio[5321]: verbose: CURRENT POSITION 7 Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:01 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:01 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:01 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:01 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:01 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:01 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100465,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"686 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:01 primo volumio[5321]: verbose: CURRENT POSITION 7 Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:01 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:01 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:01 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:01 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:01 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:01 primo volumio[5321]: info: ------------------------------ 74ms Apr 25 19:30:01 primo volumio[5321]: info: ------------------------------ 73ms Apr 25 19:30:01 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:01 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:01 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:01 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:01 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:01 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:01 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:01 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:01 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:01 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:01 primo volumio[5321]: info: MCU Signalled Playback Active Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::volumioPause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::servicePause Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::servicePause Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::pause Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand pause Apr 25 19:30:02 primo volumio[5321]: info: Apr 25 19:30:02 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand pause took 3 milliseconds Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand status took 1 milliseconds Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:02 primo volumio[5321]: info: Apr 25 19:30:02 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:02 primo volumio[5321]: info: Apr 25 19:30:02 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand status took 3 milliseconds Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand status took 2 milliseconds Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":101952,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"633 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:02 primo volumio[5321]: verbose: CURRENT POSITION 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:02 primo volumio[5321]: info: ------------------------------ 31ms Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 25 19:30:02 primo volumio[5321]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:02 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":101952,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"633 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:02 primo volumio[5321]: verbose: CURRENT POSITION 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:02 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":101952,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"633 Kbps","isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:02 primo volumio[5321]: verbose: CURRENT POSITION 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:02 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:02 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:02 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:02 primo volumio[5321]: info: ------------------------------ 57ms Apr 25 19:30:02 primo volumio[5321]: info: ------------------------------ 56ms Apr 25 19:30:02 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:02 primo volumio[5321]: info: MCU Signalled Playback Inactive Apr 25 19:30:05 primo volumio[5321]: info: Preload queue cleared Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::ClearQueue Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::stop Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::updateTrackBlock Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrackBlock Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::serviceStop Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 7 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::serviceStop Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::stop Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::clearPlayQueue Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::saveQueue Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::addQueueItems Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::addQueueItems Apr 25 19:30:05 primo volumio[5321]: info: Preload queue cleared Apr 25 19:30:05 primo volumio[5321]: info: Adding Item to queue: albums://Christian%20Gr%C3%B8vlen/BACH%20-%20Inside%20Polyphony Apr 25 19:30:05 primo volumio[5321]: info: Exploding uri albums://Christian%20Gr%C3%B8vlen/BACH%20-%20Inside%20Polyphony in service mpd Apr 25 19:30:05 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand stop took 22 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::saveQueue Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::updateTrackBlock Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrackBlock Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPlay Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::play index 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::stop Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::play index undefined Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::startPlaybackTimer Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::clearAddPlayTracks USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 24 milliseconds Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand stop took 15 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 7 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 7 milliseconds Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌","uri":"USB/512GB/蔡琴/民歌/08 渡口.flac","trackType":"flac"} Apr 25 19:30:05 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState stateService stop Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus stop Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: No code Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 76ms Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:05 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand clear took 50 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand playlistinfo took 49 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand playlistinfo took 49 milliseconds Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand add "USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf" Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:05 primo volumio[5321]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 66ms Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 65ms Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:05 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:05 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:05 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:05 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:05 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:05 primo volumio[5321]: error: updateQueue error: null Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:05 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 36ms Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand add "USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf" took 34 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 29ms Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 27ms Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand play Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:05 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:05 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 28ms Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand play took 25 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 14ms Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 13ms Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 93 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 79 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 73 milliseconds Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: Apr 25 19:30:05 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 16 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 25 19:30:05 primo volumio[5321]: info: sendMpdCommand status took 11 milliseconds Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:05 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:05 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus stop Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:05 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:05 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:05 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:05 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:05 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:05 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:05 primo volumio[5321]: info: ------------------------------ 150ms Apr 25 19:30:06 primo volumio[5321]: info: ------------------------------ 154ms Apr 25 19:30:06 primo volumio[5321]: info: ------------------------------ 152ms Apr 25 19:30:06 primo volumio[5321]: info: sendMpdCommand status took 64 milliseconds Apr 25 19:30:06 primo volumio[5321]: info: sendMpdCommand playlistinfo took 57 milliseconds Apr 25 19:30:06 primo volumio[5321]: info: sendMpdCommand playlistinfo took 55 milliseconds Apr 25 19:30:06 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:06 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:06 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:06 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":363,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:06 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:06 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:06 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:06 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:06 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:06 primo volumio[5321]: info: ------------------------------ 135ms Apr 25 19:30:06 primo volumio[5321]: info: ------------------------------ 131ms Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: sendMpdCommand playlistinfo took 124 milliseconds Apr 25 19:30:06 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:06 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:06 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:06 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:06 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:06 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:06 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:06 primo volumio[5321]: info: ------------------------------ 211ms Apr 25 19:30:06 primo volumio[5321]: info: MCU Signalled Playback Active Apr 25 19:30:06 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:06 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:07 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:07 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:07 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:07 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:07 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:07 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::volumioPause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::servicePause Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::servicePause Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::pause Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand pause Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand pause took 2 milliseconds Apr 25 19:30:11 primo volumio[5321]: info: Apr 25 19:30:11 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:11 primo volumio[5321]: info: Apr 25 19:30:11 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:11 primo volumio[5321]: info: Apr 25 19:30:11 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand status took 8 milliseconds Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand status took 5 milliseconds Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand status took 2 milliseconds Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 25 19:30:11 primo volumio[5321]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:11 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5800,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:11 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5800,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:11 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:11 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5800,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:11 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:11 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:11 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:11 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:11 primo volumio[5321]: info: ------------------------------ 52ms Apr 25 19:30:11 primo volumio[5321]: info: ------------------------------ 48ms Apr 25 19:30:11 primo volumio[5321]: info: ------------------------------ 46ms Apr 25 19:30:11 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:11 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:11 primo volumio[5321]: info: MCU Signalled Playback Inactive Apr 25 19:30:26 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 25 19:30:26 primo volumio[5321]: info: CURURI: albums://%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202 Apr 25 19:30:26 primo volumio[5321]: info: Preload queue cleared Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/02 願你平安.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/03 安穩主翅膀.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/04 慈繩愛索.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/05 耶和華你配得稱頌.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/06 神大愛.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/07 在你寶座前.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/08 我心切慕-.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/09 來投靠耶穌.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/10 我是泥土.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/11 牽引.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/12 不要怕.flac Apr 25 19:30:26 primo volumio[5321]: info: Preloading song: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/13 永不疲乏.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F01%20%E4%B8%8B%E9%9B%A8%E5%A4%A9%E7%9A%84%E5%B9%B3%E5%AE%89.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/02 願你平安.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F02%20%E9%A1%98%E4%BD%A0%E5%B9%B3%E5%AE%89.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/02 願你平安.flac Apr 25 19:30:26 primo volumio[5321]: info: Executing endpoint getSimilarAlbums Apr 25 19:30:26 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/03 安穩主翅膀.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F03%20%E5%AE%89%E7%A9%A9%E4%B8%BB%E7%BF%85%E8%86%80.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/03 安穩主翅膀.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/04 慈繩愛索.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F04%20%E6%85%88%E7%B9%A9%E6%84%9B%E7%B4%A2.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/04 慈繩愛索.flac Apr 25 19:30:26 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:26 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:26 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:26 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/05 耶和華你配得稱頌.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F05%20%E8%80%B6%E5%92%8C%E8%8F%AF%E4%BD%A0%E9%85%8D%E5%BE%97%E7%A8%B1%E9%A0%8C.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/05 耶和華你配得稱頌.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/06 神大愛.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F06%20%E7%A5%9E%E5%A4%A7%E6%84%9B.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/06 神大愛.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/07 在你寶座前.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F07%20%E5%9C%A8%E4%BD%A0%E5%AF%B6%E5%BA%A7%E5%89%8D.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/07 在你寶座前.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/08 我心切慕-.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F08%20%E6%88%91%E5%BF%83%E5%88%87%E6%85%95-.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/08 我心切慕-.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/09 來投靠耶穌.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F09%20%E4%BE%86%E6%8A%95%E9%9D%A0%E8%80%B6%E7%A9%8C.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/09 來投靠耶穌.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/10 我是泥土.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F10%20%E6%88%91%E6%98%AF%E6%B3%A5%E5%9C%9F.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/10 我是泥土.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/11 牽引.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F11%20%E7%89%BD%E5%BC%95.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/11 牽引.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/12 不要怕.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F12%20%E4%B8%8D%E8%A6%81%E6%80%95.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/12 不要怕.flac Apr 25 19:30:26 primo volumio[5321]: info: Exploding uri music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/13 永不疲乏.flac in service mpd Apr 25 19:30:26 primo volumio[5321]: info: ALBUMART /albumart?cacheid=746&web=%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5/Amazing%20Guitar%202/extralarge&path=%2Fmnt%2FUSB%2F512GB%2F1.%20Christian-FLAC%2F%E5%9F%BA%E6%81%A9%E6%95%AC%E6%8B%9C%E9%9F%B3%E6%A8%82%E4%BA%8B%E5%B7%A5%2FAmazing%20Guitar%202%2F13%20%E6%B0%B8%E4%B8%8D%E7%96%B2%E4%B9%8F.flac&metadata=false Apr 25 19:30:26 primo volumio[5321]: info: URI /mnt/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/13 永不疲乏.flac Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioPlay Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::play index undefined Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::startPlaybackTimer Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::resume Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand play Apr 25 19:30:27 primo volumio[5321]: info: Apr 25 19:30:27 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand play took 21 milliseconds Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:27 primo volumio[5321]: info: Apr 25 19:30:27 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:27 primo volumio[5321]: info: Apr 25 19:30:27 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand status took 8 milliseconds Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand status took 5 milliseconds Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand status took 7 milliseconds Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5940,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:27 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:27 primo volumio[5321]: info: ------------------------------ 32ms Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 25 19:30:27 primo volumio[5321]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 25 19:30:27 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5983,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:27 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:27 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:27 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6051,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:27 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:27 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:27 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:27 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:27 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:27 primo volumio[5321]: info: ------------------------------ 85ms Apr 25 19:30:27 primo volumio[5321]: info: ------------------------------ 85ms Apr 25 19:30:27 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:27 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:27 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:27 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:27 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:27 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:27 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:27 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:27 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:27 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:27 primo volumio[5321]: info: MCU Signalled Playback Active Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::volumioPause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::servicePause Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::servicePause Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::pause Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand pause Apr 25 19:30:29 primo volumio[5321]: info: Apr 25 19:30:29 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand pause took 4 milliseconds Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:29 primo volumio[5321]: info: Apr 25 19:30:29 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:29 primo volumio[5321]: info: Apr 25 19:30:29 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand status took 7 milliseconds Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand status took 4 milliseconds Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand status took 3 milliseconds Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 25 19:30:29 primo volumio[5321]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:29 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8213,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:29 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8213,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:29 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:29 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8213,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:29 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:29 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:29 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:29 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:29 primo volumio[5321]: info: ------------------------------ 43ms Apr 25 19:30:29 primo volumio[5321]: info: ------------------------------ 41ms Apr 25 19:30:29 primo volumio[5321]: info: ------------------------------ 40ms Apr 25 19:30:29 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:29 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:29 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:29 primo volumio[5321]: info: MCU Signalled Playback Inactive Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioPlay Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::play index undefined Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::startPlaybackTimer Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioGetVisibleSources Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::resume Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand play Apr 25 19:30:31 primo volumio[5321]: info: Apr 25 19:30:31 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand play took 25 milliseconds Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:31 primo volumio[5321]: info: Apr 25 19:30:31 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:31 primo volumio[5321]: info: Apr 25 19:30:31 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand status took 6 milliseconds Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand status took 3 milliseconds Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand status took 2 milliseconds Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 25 19:30:31 primo volumio[5321]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:31 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8276,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:31 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8291,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:31 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:31 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:31 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8308,"duration":415,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"2L-139_stereo_DSF_5644k_1b_01","artist":"Christian Grøvlen","album":"BACH - Inside Polyphony","uri":"USB/512GB/DSD Samples/DSD128/2L-139_stereo_DSF_5644k_1b_01.dsf","trackType":"dsf"} Apr 25 19:30:31 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:31 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:31 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:31 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:31 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:31 primo volumio[5321]: info: ------------------------------ 62ms Apr 25 19:30:31 primo volumio[5321]: info: ------------------------------ 60ms Apr 25 19:30:31 primo volumio[5321]: info: ------------------------------ 60ms Apr 25 19:30:31 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:31 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:31 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:31 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:31 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:31 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:31 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:31 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:31 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:31 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:31 primo volumio[5321]: info: MCU Signalled Playback Active Apr 25 19:30:34 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:34 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:38 primo volumio[5321]: info: Preload queue cleared Apr 25 19:30:38 primo volumio[5321]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::ClearQueue Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::stop Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::updateTrackBlock Apr 25 19:30:38 primo volumio[5321]: info: CorePlayQueue::getTrackBlock Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:38 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:38 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:38 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::serviceStop Apr 25 19:30:38 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:38 primo volumio[5321]: info: CoreCommandRouter::serviceStop Apr 25 19:30:38 primo volumio[5321]: info: ControllerMpd::stop Apr 25 19:30:38 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 19:30:38 primo volumio[5321]: info: CorePlayQueue::clearPlayQueue Apr 25 19:30:38 primo volumio[5321]: info: CorePlayQueue::saveQueue Apr 25 19:30:38 primo volumio[5321]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:30:38 primo volumio[5321]: info: CoreStateMachine::addQueueItems Apr 25 19:30:38 primo volumio[5321]: info: CorePlayQueue::addQueueItems Apr 25 19:30:38 primo volumio[5321]: info: Preload queue cleared Apr 25 19:30:38 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac Apr 25 19:30:38 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac Apr 25 19:30:38 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/02 願你平安.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/02 願你平安.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/03 安穩主翅膀.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/03 安穩主翅膀.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/04 慈繩愛索.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/04 慈繩愛索.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/05 耶和華你配得稱頌.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/05 耶和華你配得稱頌.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/06 神大愛.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/06 神大愛.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/07 在你寶座前.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/07 在你寶座前.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/08 我心切慕-.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/08 我心切慕-.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/09 來投靠耶穌.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/09 來投靠耶穌.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/10 我是泥土.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/10 我是泥土.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/11 牽引.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/11 牽引.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/12 不要怕.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/12 不要怕.flac Apr 25 19:30:39 primo volumio[5321]: info: Adding Item to queue: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/13 永不疲乏.flac Apr 25 19:30:39 primo volumio[5321]: info: Using cached record of: music-library/USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/13 永不疲乏.flac Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::saveQueue Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::updateTrackBlock Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrackBlock Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPlay Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::play index 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::stop Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::play index undefined Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::startPlaybackTimer Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::clearAddPlayTracks USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand stop took 44 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand stop took 19 milliseconds Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:39 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 9 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand clear took 7 milliseconds Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand add "USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac" Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: error: updateQueue error: null Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:39 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 10ms Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand add "USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac" took 8 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 5 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 4 milliseconds Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand play Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: 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} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService stop Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus stop Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: No code Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: 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} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService stop Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus stop Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: No code Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 60ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 77ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 77ms Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:39 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:39 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 95ms Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand play took 93 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 20ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 19ms Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces system playlist update Apr 25 19:30:39 primo volumio[5321]: info: Ignoring MPD Status Update Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: MCU Signalled Playback Inactive Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 30 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 29ms Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 27 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 26 milliseconds Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: Apr 25 19:30:39 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 54 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 71 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 70 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand status took 69 milliseconds Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus stop Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:39 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:39 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 170ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 244ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 244ms Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 202 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 201 milliseconds Apr 25 19:30:39 primo volumio[5321]: info: sendMpdCommand playlistinfo took 202 milliseconds Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:39 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:39 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:39 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState stateService play Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus play Apr 25 19:30:39 primo volumio[5321]: info: Received an update from plugin. extracting info from payload Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:39 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:39 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 369ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 363ms Apr 25 19:30:39 primo volumio[5321]: info: ------------------------------ 364ms Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Signalling Playback active due to playback status change Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:39 primo volumio[5321]: info: MCU Signalled Playback Active Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::volumioPause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::servicePause Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::servicePause Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::pause Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand pause Apr 25 19:30:40 primo volumio[5321]: info: Apr 25 19:30:40 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand pause took 10 milliseconds Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand status took 1 milliseconds Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:40 primo volumio[5321]: info: Apr 25 19:30:40 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:40 primo volumio[5321]: info: Apr 25 19:30:40 primo volumio[5321]: ---------------------------- MPD announces state update: player Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::getState Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand status Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand status took 3 milliseconds Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand status took 2 milliseconds Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::parseState Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2471,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"547 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:40 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:40 primo volumio[5321]: info: ------------------------------ 21ms Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 25 19:30:40 primo volumio[5321]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 25 19:30:40 primo volumio[5321]: verbose: ControllerMpd::parseTrackInfo Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2471,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"547 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:40 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:40 primo volumio[5321]: info: ControllerMpd::pushState Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::servicePushState Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2471,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"547 Kbps","isStreaming":false,"title":"下雨天的平安","artist":"基恩敬拜音樂事工","album":"Amazing Guitar 2","uri":"USB/512GB/1. Christian-FLAC/基恩敬拜音樂事工/Amazing Guitar 2/01 下雨天的平安.flac","trackType":"flac"} Apr 25 19:30:40 primo volumio[5321]: verbose: CURRENT POSITION 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::syncState stateService pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::syncState currentStatus pause Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::pushState Apr 25 19:30:40 primo volumio[5321]: info: CorePlayQueue::getTrack 0 Apr 25 19:30:40 primo volumio[5321]: info: CoreCommandRouter::volumioPushState Apr 25 19:30:40 primo volumio[5321]: info: CoreStateMachine::stPlaybackTimer Apr 25 19:30:40 primo volumio[5321]: info: ------------------------------ 52ms Apr 25 19:30:40 primo volumio[5321]: info: ------------------------------ 51ms Apr 25 19:30:40 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:40 primo volumio[5321]: info: Updating RAAT Signal Path Apr 25 19:30:40 primo volumio[5321]: info: MCU Signalled Playback Inactive Apr 25 19:30:41 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:41 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:41 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:41 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:30:41 primo volumio[5321]: info: Executing endpoint metavolumio Apr 25 19:30:41 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:31:02 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 25 19:31:02 primo volumio[5321]: info: Preload queue cleared Apr 25 19:31:03 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 25 19:31:03 primo volumio[5321]: info: Preload queue cleared Apr 25 19:31:05 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 25 19:31:05 primo volumio[5321]: info: Preload queue cleared Apr 25 19:31:10 primo volumio[5321]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 25 19:31:11 primo volumio[5321]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 19:31:11 primo volumio[5321]: TypeError: Cannot read property 'length' of undefined Apr 25 19:31:11 primo volumio[5321]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 25 19:31:11 primo volumio[5321]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 25 19:31:11 primo volumio[5321]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 25 19:31:11 primo volumio[5321]: at Parser.emit (events.js:400:28) Apr 25 19:31:11 primo volumio[5321]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 25 19:31:11 primo volumio[5321]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 25 19:31:11 primo volumio[5321]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 25 19:31:11 primo volumio[5321]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 25 19:31:11 primo volumio[5321]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 25 19:31:11 primo volumio[5321]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 25 19:31:11 primo volumio[5321]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 25 19:31:11 primo volumio[5321]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 25 19:31:11 primo volumio[5321]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 25 19:31:11 primo volumio[5321]: at IncomingMessage.emit (events.js:412:35) Apr 25 19:31:11 primo volumio[5321]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 25 19:31:11 primo volumio[5321]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 25 19:31:11 primo volumio[5321]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 19:31:11 primo sudo[7836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-25 19:30 Apr 25 19:31:11 primo sudo[7836]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 12:01:04 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="728189e850980f21ef569472434ec23d"