Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 350 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 350 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand stop took 21 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/14 - Keshi - 2 Soon.mp3 Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand stop took 2 milliseconds Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:47:30 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand clear took 6 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/14 - Keshi - 2 Soon.mp3" Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: 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} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: No code Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: 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} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: No code Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 66ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 65ms Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:47:30 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:47:30 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:47:30 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:47:30 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 70ms Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/14 - Keshi - 2 Soon.mp3" took 69 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 7ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 5ms Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:47:30 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 80ms Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:47:30 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:47:30 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 28ms Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand play took 24 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 22ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 21ms Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 31 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 31 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 30 milliseconds Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: Oct 16 00:47:30 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 9 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":461,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:30 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":461,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:30 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 66ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 90ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 90ms Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 16 00:47:30 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:30 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:30 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:30 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:30 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:30 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:30 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 124ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 120ms Oct 16 00:47:30 volumio volumio[4611]: info: ------------------------------ 119ms Oct 16 00:47:31 volumio ntpd[990]: PROTO: 193.182.111.12 unlink local addr 192.168.1.10 -> Oct 16 00:47:35 volumio ntpd[990]: PROTO: 84.16.73.33 unlink local addr 192.168.1.10 -> Oct 16 00:47:38 volumio ntpd[990]: PROTO: 162.159.200.123 unlink local addr 192.168.1.10 -> Oct 16 00:47:42 volumio sudo[5921]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 00:47:42 volumio sudo[5921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 00:47:42 volumio sudo[5923]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 00:47:42 volumio sudo[5921]: pam_unix(sudo:session): session closed for user root Oct 16 00:47:42 volumio sudo[5923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 00:47:42 volumio sudo[5923]: pam_unix(sudo:session): session closed for user root Oct 16 00:47:43 volumio volumio[4611]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 10; HarmonyOS; HMA-AL00; HMSCore 6.16.2.342; GMSCore 25.20.37) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.6834.79 HuaweiBrowser/17.0.7.302 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:43 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 00:47:43 volumio volumio[4611]: info: Received Get System Info Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 00:47:43 volumio volumio[4611]: info: Discovery: Getting this device information Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:43 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:43 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:43 volumio volumio[4611]: info: Listing playlists Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 00:47:43 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 00:47:44 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 00:47:44 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 00:47:44 volumio volumio[4611]: info: Received Get System Info Oct 16 00:47:44 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 00:47:44 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 00:47:44 volumio volumio[4611]: info: Discovery: Getting this device information Oct 16 00:47:44 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:44 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:44 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 00:47:51 volumio kernel: perf: interrupt took too long (3162 > 3137), lowering kernel.perf_event_max_sample_rate to 63250 Oct 16 00:47:52 volumio sudo[5959]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 00:47:52 volumio sudo[5959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 00:47:52 volumio sudo[5961]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 00:47:52 volumio sudo[5959]: pam_unix(sudo:session): session closed for user root Oct 16 00:47:52 volumio sudo[5961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 00:47:52 volumio sudo[5961]: pam_unix(sudo:session): session closed for user root Oct 16 00:47:52 volumio volumio[4611]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.104 UA: Mozilla/5.0 (Linux; Android 10; HarmonyOS; HMA-AL00; HMSCore 6.16.2.342; GMSCore 25.20.37) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/132.0.6834.79 HuaweiBrowser/17.0.7.302 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:52 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 00:47:52 volumio volumio[4611]: info: Received Get System Info Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 00:47:52 volumio volumio[4611]: info: Discovery: Getting this device information Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:52 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:52 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:52 volumio volumio[4611]: info: Listing playlists Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 00:47:52 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 00:47:54 volumio volumio[4611]: info: Received Get System Info Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 00:47:54 volumio volumio[4611]: info: Discovery: Getting this device information Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::volumioGetState Oct 16 00:47:54 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::volumioSeek Oct 16 00:47:54 volumio volumio[4611]: info: CoreStateMachine::seek Oct 16 00:47:54 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:54 volumio volumio[4611]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/14 - Keshi - 2 Soon.mp3","service":"mpd","name":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=23&web=Keshi/January%20Hits%202020%20Plus/extralarge&path=%2FINTERNAL&metadata=false","duration":219,"trackType":"mp3","samplerate":"48 kHz","bitdepth":"16 bit","channels":2} Oct 16 00:47:54 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:47:54 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:54 volumio volumio[4611]: info: ControllerMpd::seek Oct 16 00:47:54 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:54 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:54 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: error: null Oct 16 00:47:55 volumio volumio[4611]: info: Oct 16 00:47:55 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:55 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:55 volumio volumio[4611]: info: Oct 16 00:47:55 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:55 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:55 volumio volumio[4611]: info: Oct 16 00:47:55 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:55 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:55 volumio volumio[4611]: info: sendMpdCommand status took 7 milliseconds Oct 16 00:47:55 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:47:55 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:55 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:47:55 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:47:55 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:55 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:55 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39712,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:55 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:55 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:55 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:55 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:55 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:55 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:55 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:55 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:55 volumio volumio[4611]: info: ------------------------------ 60ms Oct 16 00:47:55 volumio volumio[4611]: info: ------------------------------ 59ms Oct 16 00:47:55 volumio volumio[4611]: info: ------------------------------ 59ms Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioSeek Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::seek Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/14 - Keshi - 2 Soon.mp3","service":"mpd","name":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=23&web=Keshi/January%20Hits%202020%20Plus/extralarge&path=%2FINTERNAL&metadata=false","duration":219,"trackType":"mp3","samplerate":"48 kHz","bitdepth":"16 bit","channels":2} Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::seek Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: error: null Oct 16 00:47:56 volumio volumio[4611]: info: Oct 16 00:47:56 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:56 volumio volumio[4611]: info: Oct 16 00:47:56 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:56 volumio volumio[4611]: info: Oct 16 00:47:56 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:56 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:47:56 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:47:56 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:56 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:47:56 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:47:56 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:56 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106461,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:56 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:56 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106461,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:56 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:56 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106461,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:56 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:56 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:56 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:56 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:56 volumio volumio[4611]: info: ------------------------------ 56ms Oct 16 00:47:56 volumio volumio[4611]: info: ------------------------------ 54ms Oct 16 00:47:56 volumio volumio[4611]: info: ------------------------------ 53ms Oct 16 00:47:57 volumio ntpd[990]: PROTO: 84.16.67.12 unlink local addr 192.168.1.10 -> Oct 16 00:47:57 volumio volumio[4611]: info: CoreCommandRouter::volumioSeek Oct 16 00:47:57 volumio volumio[4611]: info: CoreStateMachine::seek Oct 16 00:47:57 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:57 volumio volumio[4611]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/14 - Keshi - 2 Soon.mp3","service":"mpd","name":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=23&web=Keshi/January%20Hits%202020%20Plus/extralarge&path=%2FINTERNAL&metadata=false","duration":219,"trackType":"mp3","samplerate":"48 kHz","bitdepth":"16 bit","channels":2} Oct 16 00:47:57 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:47:57 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:57 volumio volumio[4611]: info: ControllerMpd::seek Oct 16 00:47:57 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:57 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:57 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:57 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:57 volumio volumio[4611]: error: null Oct 16 00:47:57 volumio volumio[4611]: info: Oct 16 00:47:57 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:57 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:57 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:57 volumio volumio[4611]: info: Oct 16 00:47:57 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:58 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:58 volumio volumio[4611]: info: Oct 16 00:47:58 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:58 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:58 volumio volumio[4611]: info: sendMpdCommand status took 7 milliseconds Oct 16 00:47:58 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:47:58 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:58 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:47:58 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:47:58 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:58 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:58 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177733,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:58 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:58 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:58 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:58 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:58 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:58 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177964,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:58 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:58 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:58 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:58 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:58 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:58 volumio volumio[4611]: info: ------------------------------ 65ms Oct 16 00:47:58 volumio volumio[4611]: info: ------------------------------ 63ms Oct 16 00:47:58 volumio volumio[4611]: info: ------------------------------ 62ms Oct 16 00:47:58 volumio ntpd[990]: PROTO: 5.79.108.34 unlink local addr 192.168.1.10 -> Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioSeek Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::seek Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/14 - Keshi - 2 Soon.mp3","service":"mpd","name":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=23&web=Keshi/January%20Hits%202020%20Plus/extralarge&path=%2FINTERNAL&metadata=false","duration":219,"trackType":"mp3","samplerate":"48 kHz","bitdepth":"16 bit","channels":2} Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::seek Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: error: null Oct 16 00:47:59 volumio volumio[4611]: info: Oct 16 00:47:59 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:59 volumio volumio[4611]: info: Oct 16 00:47:59 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:59 volumio volumio[4611]: info: Oct 16 00:47:59 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:47:59 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:47:59 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:47:59 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:47:59 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:47:59 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:47:59 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:59 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171000,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:59 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:59 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171000,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:59 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:59 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171000,"duration":219,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2 Soon","artist":"Keshi","album":"January Hits 2020 Plus","uri":"INTERNAL/14 - Keshi - 2 Soon.mp3","trackType":"mp3"} Oct 16 00:47:59 volumio volumio[4611]: verbose: CURRENT POSITION 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:47:59 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:47:59 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:47:59 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:47:59 volumio volumio[4611]: info: ------------------------------ 65ms Oct 16 00:47:59 volumio volumio[4611]: info: ------------------------------ 64ms Oct 16 00:47:59 volumio volumio[4611]: info: ------------------------------ 63ms Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 315 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand stop took 24 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Supermans Feinde - Shine.mp3 Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand stop took 2 milliseconds Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:01 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 7 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand clear took 4 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Supermans Feinde - Shine.mp3" Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: 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} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: No code Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: 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} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: No code Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 47ms Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 48ms Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:01 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:01 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:01 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:01 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 52ms Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/Supermans Feinde - Shine.mp3" took 50 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 5ms Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 4ms Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:48:01 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 62ms Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:01 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:01 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 25ms Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand play took 21 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 21ms Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 20ms Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 58 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 56 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 55 milliseconds Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: Oct 16 00:48:01 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 11 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 8 milliseconds Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":684,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:01 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:01 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 103ms Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 122ms Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 121ms Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand status took 57 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:01 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:01 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 114ms Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 104ms Oct 16 00:48:01 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 70 milliseconds Oct 16 00:48:01 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:01 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:01 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:01 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:01 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:01 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:01 volumio volumio[4611]: info: ------------------------------ 153ms Oct 16 00:48:02 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioSeek Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::seek Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Supermans Feinde - Shine.mp3","service":"mpd","name":"Shine","artist":"Supermans Feinde","album":"Shine","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=23&web=Supermans%20Feinde/Shine/extralarge&path=%2FINTERNAL&metadata=false","duration":219,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::seek Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: error: null Oct 16 00:48:04 volumio volumio[4611]: info: Oct 16 00:48:04 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:04 volumio volumio[4611]: info: Oct 16 00:48:04 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:04 volumio volumio[4611]: info: Oct 16 00:48:04 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:04 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:04 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:04 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:04 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:04 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:04 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:04 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49456,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:04 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:04 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49456,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:04 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:04 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49456,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:04 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:04 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:04 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:04 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:04 volumio volumio[4611]: info: ------------------------------ 44ms Oct 16 00:48:04 volumio volumio[4611]: info: ------------------------------ 45ms Oct 16 00:48:04 volumio volumio[4611]: info: ------------------------------ 44ms Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioSeek Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::seek Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Supermans Feinde - Shine.mp3","service":"mpd","name":"Shine","artist":"Supermans Feinde","album":"Shine","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=23&web=Supermans%20Feinde/Shine/extralarge&path=%2FINTERNAL&metadata=false","duration":219,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::seek Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: error: null Oct 16 00:48:05 volumio volumio[4611]: info: Oct 16 00:48:05 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:05 volumio volumio[4611]: info: Oct 16 00:48:05 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:05 volumio volumio[4611]: info: Oct 16 00:48:05 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:05 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:05 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:48:05 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:05 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:05 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:48:05 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:05 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45456,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:05 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:05 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45456,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:05 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:05 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45456,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:05 volumio volumio[4611]: verbose: CURRENT POSITION 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:05 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:05 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:05 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:05 volumio volumio[4611]: info: ------------------------------ 53ms Oct 16 00:48:05 volumio volumio[4611]: info: ------------------------------ 51ms Oct 16 00:48:05 volumio volumio[4611]: info: ------------------------------ 51ms Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 409 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand stop took 20 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3 Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand stop took 2 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:10 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand clear took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3" Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: 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":"Shine","artist":"Supermans Feinde","album":"Shine","uri":"INTERNAL/Supermans Feinde - Shine.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: No code Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:10 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 31ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 28ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 28ms Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:10 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:10 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:10 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:10 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 26ms Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3" took 26 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 2ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 2ms Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:10 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:10 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 18ms Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand play took 18 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 18ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 17ms Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 46 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 45 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 42 milliseconds Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: Oct 16 00:48:10 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:10 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83,"duration":211,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:10 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 75ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 87ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 87ms Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 16 00:48:10 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":964,"duration":211,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:10 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":964,"duration":211,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:10 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":964,"duration":211,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:10 volumio volumio[4611]: verbose: CURRENT POSITION 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:10 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:10 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:10 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 104ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 102ms Oct 16 00:48:10 volumio volumio[4611]: info: ------------------------------ 101ms Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 318 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand stop took 21 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Laidback - Happy Dreamer.mp3 Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand stop took 3 milliseconds Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:14 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand clear took 3 milliseconds Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Laidback - Happy Dreamer.mp3" Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: 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":"Treat People With Kindness","artist":"Harry Styles","album":"January Hits 2020 Plus","uri":"INTERNAL/16 - Harry Styles - Treat People With Kindness.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: No code Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 30ms Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:14 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:14 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:14 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:14 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 25ms Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/Laidback - Happy Dreamer.mp3" took 24 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 3ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 2ms Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:48:14 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:14 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 31ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 31ms Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:14 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:14 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 12ms Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand play took 10 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 10ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 9ms Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 43 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 44 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 44 milliseconds Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: Oct 16 00:48:14 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 1 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Happy Dreamer","artist":"Laidback","album":"Buddha-Bar VII","uri":"INTERNAL/Laidback - Happy Dreamer.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":593,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Happy Dreamer","artist":"Laidback","album":"Buddha-Bar VII","uri":"INTERNAL/Laidback - Happy Dreamer.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:14 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":707,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Happy Dreamer","artist":"Laidback","album":"Buddha-Bar VII","uri":"INTERNAL/Laidback - Happy Dreamer.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:14 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 61ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 77ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 77ms Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 16 00:48:14 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Happy Dreamer","artist":"Laidback","album":"Buddha-Bar VII","uri":"INTERNAL/Laidback - Happy Dreamer.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:14 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Happy Dreamer","artist":"Laidback","album":"Buddha-Bar VII","uri":"INTERNAL/Laidback - Happy Dreamer.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:14 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Happy Dreamer","artist":"Laidback","album":"Buddha-Bar VII","uri":"INTERNAL/Laidback - Happy Dreamer.mp3","trackType":"mp3"} Oct 16 00:48:14 volumio volumio[4611]: verbose: CURRENT POSITION 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:14 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:14 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:14 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 86ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 80ms Oct 16 00:48:14 volumio volumio[4611]: info: ------------------------------ 80ms Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 178 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand stop took 22 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3 Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand stop took 1 milliseconds Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:17 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand clear took 3 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 1 milliseconds Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3" Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: 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} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: No code Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: 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} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: No code Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 55ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 55ms Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:17 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:17 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:17 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:17 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 57ms Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3" took 55 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 3ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 3ms Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:48:17 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 63ms Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:17 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:17 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 23ms Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand play took 20 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 19ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 19ms Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: Oct 16 00:48:17 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:17 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:17 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 26ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 50ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 50ms Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 62 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 16 00:48:17 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:17 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:17 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:17 volumio volumio[4611]: verbose: CURRENT POSITION 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:17 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:17 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:17 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 133ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 132ms Oct 16 00:48:17 volumio volumio[4611]: info: ------------------------------ 131ms Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 379 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand stop took 20 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3 Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand stop took 3 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:20 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand clear took 2 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3" Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: 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":"Octahate (Natio Remix)","artist":"Natio/Ryn Weaver","album":"Octahate","uri":"INTERNAL/Natio,Ryn Weaver - Octahate (Natio Remix).mp3","trackType":"mp3"} Oct 16 00:48:20 volumio volumio[4611]: verbose: CURRENT POSITION 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: No code Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 25ms Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 23ms Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:20 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:20 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:20 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:20 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 22ms Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3" took 20 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 2ms Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:48:20 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 25ms Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:20 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 21ms Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 20ms Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand play took 19 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 18ms Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:20 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 16ms Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 16 milliseconds Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: Oct 16 00:48:20 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 12 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 7 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ariana Grande feat. Zedd - Break Free (Extended Mix)","artist":null,"album":null,"uri":"INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3","trackType":"mp3"} Oct 16 00:48:20 volumio volumio[4611]: verbose: CURRENT POSITION 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 25ms Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:20 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ariana Grande feat. Zedd - Break Free (Extended Mix)","artist":null,"album":null,"uri":"INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3","trackType":"mp3"} Oct 16 00:48:20 volumio volumio[4611]: verbose: CURRENT POSITION 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:20 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ariana Grande feat. Zedd - Break Free (Extended Mix)","artist":null,"album":null,"uri":"INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3","trackType":"mp3"} Oct 16 00:48:20 volumio volumio[4611]: verbose: CURRENT POSITION 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:20 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ariana Grande feat. Zedd - Break Free (Extended Mix)","artist":null,"album":null,"uri":"INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3","trackType":"mp3"} Oct 16 00:48:20 volumio volumio[4611]: verbose: CURRENT POSITION 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:20 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ariana Grande feat. Zedd - Break Free (Extended Mix)","artist":null,"album":null,"uri":"INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3","trackType":"mp3"} Oct 16 00:48:20 volumio volumio[4611]: verbose: CURRENT POSITION 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:20 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:20 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:20 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 89ms Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 83ms Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 81ms Oct 16 00:48:20 volumio volumio[4611]: info: ------------------------------ 82ms Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioNext Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::next Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::stop Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::stPlaybackTimer Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::serviceStop Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 12 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::serviceStop Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::stop Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand stop took 22 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::play index undefined Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::startPlaybackTimer Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/逃跑计划 - 夜空中最亮的星.mp3 Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::updateTrackBlock Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrackBlock Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand stop took 2 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 1 milliseconds Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:22 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand clear took 2 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/逃跑计划 - 夜空中最亮的星.mp3" Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: 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":"Ariana Grande feat. Zedd - Break Free (Extended Mix)","artist":null,"album":null,"uri":"INTERNAL/Ariana Grande feat. Zedd - Break Free (Extended Mix).mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: No code Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: 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} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService stop Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: No code Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 45ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 44ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 42ms Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:22 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:22 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:22 volumio volumio[4611]: error: updateQueue error: null Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:22 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 44ms Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand add "INTERNAL/逃跑计划 - 夜空中最亮的星.mp3" took 45 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 4ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 4ms Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand play Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:22 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces system playlist update Oct 16 00:48:22 volumio volumio[4611]: info: Ignoring MPD Status Update Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 19ms Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand play took 15 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 15ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 14ms Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 6 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 5 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: Oct 16 00:48:22 volumio volumio[4611]: ---------------------------- MPD announces state update: player Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::getState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 36 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 34 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand status took 34 milliseconds Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseState Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":798,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"夜空中最亮的星","artist":"逃跑计划","album":null,"uri":"INTERNAL/逃跑计划 - 夜空中最亮的星.mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":821,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"夜空中最亮的星","artist":"逃跑计划","album":null,"uri":"INTERNAL/逃跑计划 - 夜空中最亮的星.mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:22 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"夜空中最亮的星","artist":"逃跑计划","album":null,"uri":"INTERNAL/逃跑计划 - 夜空中最亮的星.mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:22 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 59ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 74ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 73ms Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 16 00:48:22 volumio volumio[4611]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: verbose: ControllerMpd::parseTrackInfo Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"夜空中最亮的星","artist":"逃跑计划","album":null,"uri":"INTERNAL/逃跑计划 - 夜空中最亮的星.mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:22 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"夜空中最亮的星","artist":"逃跑计划","album":null,"uri":"INTERNAL/逃跑计划 - 夜空中最亮的星.mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:22 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: ControllerMpd::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::servicePushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"夜空中最亮的星","artist":"逃跑计划","album":null,"uri":"INTERNAL/逃跑计划 - 夜空中最亮的星.mp3","trackType":"mp3"} Oct 16 00:48:22 volumio volumio[4611]: verbose: CURRENT POSITION 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState stateService play Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::syncState currentStatus play Oct 16 00:48:22 volumio volumio[4611]: info: Received an update from plugin. extracting info from payload Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: CoreStateMachine::pushState Oct 16 00:48:22 volumio volumio[4611]: info: CorePlayQueue::getTrack 93 Oct 16 00:48:22 volumio volumio[4611]: info: CoreCommandRouter::volumioPushState Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 133ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 131ms Oct 16 00:48:22 volumio volumio[4611]: info: ------------------------------ 131ms Oct 16 00:48:24 volumio volumio[4611]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 00:48:24 volumio volumio[4611]: TypeError: Cannot read properties of null (reading 'slice') Oct 16 00:48:24 volumio volumio[4611]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 00:48:24 volumio volumio[4611]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 00:48:24 volumio volumio[4611]: at Object.onceWrapper (node:events:629:26) Oct 16 00:48:24 volumio volumio[4611]: at ClientRequest.emit (node:events:514:28) Oct 16 00:48:24 volumio volumio[4611]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Oct 16 00:48:24 volumio volumio[4611]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Oct 16 00:48:24 volumio volumio[4611]: at TLSSocket.socketOnData (node:_http_client:535:22) Oct 16 00:48:24 volumio volumio[4611]: at TLSSocket.emit (node:events:514:28) Oct 16 00:48:24 volumio volumio[4611]: at addChunk (node:internal/streams/readable:343:12) Oct 16 00:48:24 volumio volumio[4611]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 16 00:48:24 volumio volumio[4611]: at Readable.push (node:internal/streams/readable:253:10) Oct 16 00:48:24 volumio volumio[4611]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Oct 16 00:48:24 volumio volumio[4611]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 00:48:24 volumio sudo[6087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 00:47' Oct 16 00:48:24 volumio sudo[6087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"