-- 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"