Feb 23 13:54:04 volumio volumio[1329]: info: CoreCommandRouter::volumioGetState Feb 23 13:54:04 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioNext Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::next Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::serviceStop Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::serviceStop Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::stop Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand stop took 7 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::play index undefined Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::startPlaybackTimer Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3 Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand stop took 0 milliseconds Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:07 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand clear took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3" Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: 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":"Sest dní na silnici","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/01 - Sest dni na silnici.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: No code Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: 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} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: No code Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: 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} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: No code Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 13ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 11ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 11ms Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:07 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:07 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:07 volumio volumio[1329]: error: updateQueue error: null Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:07 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 12ms Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand add "INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3" took 12 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:07 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:07 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 4ms Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand play took 3 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 3ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 3ms Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 13 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 14 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 13 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 0 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":102,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 15ms Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: Feb 23 13:54:07 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand status took 0 milliseconds Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":102,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:07 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":102,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:07 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":102,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:07 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 24ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 23ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 11ms Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 13:54:07 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":102,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:07 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":102,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:07 volumio volumio[1329]: verbose: CURRENT POSITION 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:07 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:07 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:07 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 14ms Feb 23 13:54:07 volumio volumio[1329]: info: ------------------------------ 14ms Feb 23 13:54:18 volumio volumio[1329]: info: CoreCommandRouter::volumioGetState Feb 23 13:54:18 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:54:22 volumio volumio[1329]: info: Retrieving Cloud Streaming UI Feb 23 13:54:22 volumio volumio[1329]: info: Getting Tidal Cloud Configuration Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:54:22 volumio volumio[1329]: info: Getting Qobuz Cloud Configuration Feb 23 13:54:22 volumio volumio[1329]: info: Asking plugin for UI Config Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:54:22 volumio volumio[1329]: info: Getting Spotify Cloud Configuration Feb 23 13:54:22 volumio volumio[1329]: info: Asking plugin for UI Config Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:54:22 volumio volumio[1329]: info: Saving Spotify Acccount Feb 23 13:54:22 volumio volumio[1329]: info: Got Tidal Cloud Configuration Feb 23 13:54:22 volumio volumio[1329]: info: Got it Feb 23 13:54:22 volumio volumio[1329]: info: Got it Feb 23 13:54:22 volumio volumio[1329]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 13:54:22 volumio volumio[1329]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 13:54:22 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 23 13:54:26 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 13:54:30 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 13:54:34 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 23 13:54:36 volumio volumio[1329]: info: CoreCommandRouter::volumioGetState Feb 23 13:54:36 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioNext Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::next Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::stop Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::serviceStop Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::serviceStop Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::stop Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand stop took 7 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::play index undefined Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::startPlaybackTimer Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3 Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 6 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand stop took 5 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 5 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:38 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand clear took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3" Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: 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":"Slapej dál","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/02 - Slapej dal.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: No code Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 13:54:38 volumio volumio[1329]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 13ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 12ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 12ms Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:38 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:38 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:38 volumio volumio[1329]: error: updateQueue error: null Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:38 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 8ms Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand add "INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3" took 7 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 0ms Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:38 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:38 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 5ms Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand play took 5 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 4ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 4ms Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 12 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 12 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 12 milliseconds Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: Feb 23 13:54:38 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 4 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:38 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:38 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 21ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 24ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 23ms Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 13:54:38 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:38 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:38 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":184,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:38 volumio volumio[1329]: verbose: CURRENT POSITION 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:38 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:38 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:38 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 23ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 20ms Feb 23 13:54:38 volumio volumio[1329]: info: ------------------------------ 20ms Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioNext Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::next Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::stop Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::serviceStop Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::serviceStop Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::stop Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand stop took 8 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::play index undefined Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::startPlaybackTimer Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3 Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand stop took 2 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: 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":"Na vánoce ráno","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/03 - Na vanoce rano.mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: No code Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 7ms Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:43 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand clear took 6 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3" Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 13:54:43 volumio volumio[1329]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 8ms Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 8ms Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:43 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:43 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:43 volumio volumio[1329]: error: updateQueue error: null Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:43 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 3ms Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand add "INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3" took 3 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 2ms Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 2ms Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:43 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:54:43 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand play took 2 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 10 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 11 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 11 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 13ms Feb 23 13:54:43 volumio volumio[1329]: info: Feb 23 13:54:43 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand status took 0 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":550,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:43 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":573,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:43 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:43 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 21ms Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 22ms Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 12ms Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 23 13:54:43 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:43 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:54:43 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:54:43 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:54:43 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:54:43 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 20ms Feb 23 13:54:43 volumio volumio[1329]: info: ------------------------------ 19ms Feb 23 13:54:48 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 23 13:54:48 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:54:48 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:54:51 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 23 13:54:51 volumio volumio[1329]: info: TuneIn handleBrowseUri: tunein/location Feb 23 13:54:51 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:54:53 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 23 13:54:53 volumio volumio[1329]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101218 Feb 23 13:54:53 volumio volumio[1329]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101218 Feb 23 13:54:53 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:54:55 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 23 13:54:55 volumio volumio[1329]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100436 Feb 23 13:54:55 volumio volumio[1329]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100436 Feb 23 13:54:56 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:54:56 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:03 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 23 13:55:03 volumio volumio[1329]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100510 Feb 23 13:55:03 volumio volumio[1329]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100510 Feb 23 13:55:04 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:04 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:06 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 23 13:55:06 volumio volumio[1329]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100204 Feb 23 13:55:06 volumio volumio[1329]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100204 Feb 23 13:55:06 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:06 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:08 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::ClearQueue Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::serviceStop Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::serviceStop Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::stop Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::clearPlayQueue Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::saveQueue Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushQueue Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::addQueueItems Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::addQueueItems Feb 23 13:55:08 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:08 volumio volumio[1329]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s28159 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 23 13:55:08 volumio volumio[1329]: info: Feb 23 13:55:08 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand stop took 10 milliseconds Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:08 volumio volumio[1329]: info: Feb 23 13:55:08 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:08 volumio volumio[1329]: info: Feb 23 13:55:08 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand status took 0 milliseconds Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: 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":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:55:08 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: No code Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: 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":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:55:08 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: No code Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: 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":"Je vsechno O.K.","artist":"Zelenaci","album":"1972 - Zelenaci 72","uri":"INTERNAL/Music/Z/Zelenaci_72/04 - Je vsechno O.K..mp3","trackType":"mp3"} Feb 23 13:55:08 volumio volumio[1329]: verbose: CURRENT POSITION 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: No code Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 3 Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:08 volumio volumio[1329]: info: ------------------------------ 14ms Feb 23 13:55:08 volumio volumio[1329]: info: ------------------------------ 14ms Feb 23 13:55:08 volumio volumio[1329]: info: ------------------------------ 13ms Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPushQueue Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::saveQueue Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock Feb 23 13:55:08 volumio volumio[1329]: info: CoreCommandRouter::volumioPlay Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::play index 0 Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::stop Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::play index undefined Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:08 volumio volumio[1329]: info: CoreStateMachine::startPlaybackTimer Feb 23 13:55:08 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:08 volumio volumio[1329]: info: [1771851308424] ControllerWebradio::clearAddPlayTrack Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand stop took 0 milliseconds Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 13:55:08 volumio volumio[1329]: info: Feb 23 13:55:08 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:08 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:08 volumio volumio[1329]: info: sendMpdCommand clear took 1 milliseconds Feb 23 13:55:08 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1" Feb 23 13:55:08 volumio volumio[1329]: info: Feb 23 13:55:08 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:08 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:08 volumio volumio[1329]: info: Feb 23 13:55:08 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:08 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:08 volumio volumio[1329]: error: updateQueue error: null Feb 23 13:55:08 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:55:11 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1" Feb 23 13:55:11 volumio volumio[1329]: error: updateQueue error: null Feb 23 13:55:11 volumio volumio[1329]: error: updateQueue error: null Feb 23 13:55:11 volumio volumio[1329]: info: ------------------------------ 2837ms Feb 23 13:55:11 volumio volumio[1329]: info: ------------------------------ 2837ms Feb 23 13:55:11 volumio volumio[1329]: info: sendMpdCommand add "https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1" took 0 milliseconds Feb 23 13:55:11 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 23 13:55:11 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Feb 23 13:55:11 volumio volumio[1329]: info: Feb 23 13:55:11 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:11 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:11 volumio volumio[1329]: info: sendMpdCommand play took 1 milliseconds Feb 23 13:55:11 volumio volumio[1329]: info: Feb 23 13:55:11 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:11 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:11 volumio volumio[1329]: info: Feb 23 13:55:11 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:11 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:11 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:55:11 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:55:11 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:55:12 volumio volumio[1329]: info: Feb 23 13:55:12 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:12 volumio volumio[1329]: info: Feb 23 13:55:12 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:12 volumio volumio[1329]: info: Feb 23 13:55:12 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand status took 15 milliseconds Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:12 volumio volumio[1329]: info: Feb 23 13:55:12 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:12 volumio volumio[1329]: info: Feb 23 13:55:12 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand status took 17 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand status took 17 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","artist":null,"album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:12 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Feb 23 13:55:12 volumio volumio[1329]: info: ------------------------------ 20ms Feb 23 13:55:12 volumio volumio[1329]: info: Feb 23 13:55:12 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand status took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand status took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","artist":null,"album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:12 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:12 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","artist":null,"album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:12 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:12 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","artist":null,"album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:12 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:12 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","artist":null,"album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:12 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:12 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: ------------------------------ 36ms Feb 23 13:55:12 volumio volumio[1329]: info: ------------------------------ 37ms Feb 23 13:55:12 volumio volumio[1329]: info: ------------------------------ 22ms Feb 23 13:55:12 volumio volumio[1329]: info: ------------------------------ 21ms Feb 23 13:55:12 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 23 13:55:12 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:12 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","artist":null,"album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:12 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:12 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:12 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:12 volumio volumio[1329]: info: ------------------------------ 21ms Feb 23 13:55:13 volumio volumio[1329]: info: Feb 23 13:55:13 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:13 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:13 volumio volumio[1329]: info: Feb 23 13:55:13 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:13 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:13 volumio volumio[1329]: info: Feb 23 13:55:13 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:13 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:13 volumio volumio[1329]: info: Feb 23 13:55:13 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:13 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:13 volumio volumio[1329]: info: Feb 23 13:55:13 volumio volumio[1329]: ---------------------------- MPD announces system playlist update Feb 23 13:55:13 volumio volumio[1329]: info: Ignoring MPD Status Update Feb 23 13:55:13 volumio volumio[1329]: info: Feb 23 13:55:13 volumio volumio[1329]: ---------------------------- MPD announces state update: player Feb 23 13:55:13 volumio volumio[1329]: info: ControllerMpd::getState Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Feb 23 13:55:13 volumio volumio[1329]: info: ------------------------------ 2ms Feb 23 13:55:13 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:55:13 volumio volumio[1329]: info: ------------------------------ 1ms Feb 23 13:55:13 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:13 volumio volumio[1329]: info: ------------------------------ 2ms Feb 23 13:55:13 volumio volumio[1329]: info: sendMpdCommand status took 1 milliseconds Feb 23 13:55:13 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:55:13 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::parseState Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:13 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:13 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:13 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Police - Every Breath You Take","artist":"Rewind 94.3","album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:13 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:13 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:13 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:13 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:13 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Police - Every Breath You Take","artist":"Rewind 94.3","album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:13 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:13 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:13 volumio volumio[1329]: info: ------------------------------ 8ms Feb 23 13:55:13 volumio volumio[1329]: info: ------------------------------ 8ms Feb 23 13:55:13 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 23 13:55:13 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo Feb 23 13:55:13 volumio volumio[1329]: info: ControllerMpd::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::servicePushState Feb 23 13:55:13 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 Feb 23 13:55:13 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Police - Every Breath You Take","artist":"Rewind 94.3","album":null,"uri":"https://live.amperwave.net/direct/saga-wrndfmmp3-ibc2?source=TuneIn&gdpr=1","trackType":""} Feb 23 13:55:13 volumio volumio[1329]: verbose: CURRENT POSITION 0 Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play Feb 23 13:55:13 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreStateMachine::pushState Feb 23 13:55:13 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState Feb 23 13:55:13 volumio volumio[1329]: info: ------------------------------ 10ms Feb 23 13:55:15 volumio volumio[1329]: info: CoreCommandRouter::volumioGetState Feb 23 13:55:36 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Feb 23 13:55:36 volumio volumio[1329]: info: Preload queue cleared Feb 23 13:55:36 volumio volumio[1329]: info: Preloading song: mnt/INTERNAL/Music/J/Jethro_Tull_-1975_-_Minstrel_In_The_Gallery/Jethro_20Tull_20-_20Minstrel_20In_20The_20Gallery_20-_2005_20-_20One_20White_20Duck.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Preloading song: mnt/INTERNAL/Music/B/Beatles - White Album/ Beatles- Julia.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Preloading song: music-library/INTERNAL/Music/B/Beatles - Sgt_Pepper's_Lonely_Hearts_Club_Band/Beatles - Sgt Pepper's Lonely Hearts Club Band.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Preloading song: music-library/INTERNAL/Music/B/Beach Boys - Surfs Up/1._Beach_Boys_-_Surf's_Up_-_Don't_Go_Near_The_Water.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Preloading song: music-library/INTERNAL/Music/A/ACDC - High Voltage/ACDC_-_01_-_Its_A_Long_Way_To_The_Top.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Feb 23 13:55:36 volumio volumio[1329]: info: Exploding uri mnt/INTERNAL/Music/J/Jethro_Tull_-1975_-_Minstrel_In_The_Gallery/Jethro_20Tull_20-_20Minstrel_20In_20The_20Gallery_20-_2005_20-_20One_20White_20Duck.mp3 in service mpd Feb 23 13:55:36 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=949&web=Jethro%20Tull/Minstrel%20in%20the%20Gallery/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FJ%2FJethro_Tull_-1975_-_Minstrel_In_The_Gallery%2FJethro_20Tull_20-_20Minstrel_20In_20The_20Gallery_20-_2005_20-_20One_20White_20Duck.mp3&metadata=false Feb 23 13:55:36 volumio volumio[1329]: info: URI /mnt/INTERNAL/Music/J/Jethro_Tull_-1975_-_Minstrel_In_The_Gallery/Jethro_20Tull_20-_20Minstrel_20In_20The_20Gallery_20-_2005_20-_20One_20White_20Duck.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Exploding uri mnt/INTERNAL/Music/B/Beatles - White Album/ Beatles- Julia.mp3 in service mpd Feb 23 13:55:36 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=949&web=Beatles/White%20Album/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FB%2FBeatles%20-%20White%20Album%2F%20Beatles-%20Julia.mp3&metadata=false Feb 23 13:55:36 volumio volumio[1329]: info: URI /mnt/INTERNAL/Music/B/Beatles - White Album/ Beatles- Julia.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Exploding uri music-library/INTERNAL/Music/B/Beatles - Sgt_Pepper's_Lonely_Hearts_Club_Band/Beatles - Sgt Pepper's Lonely Hearts Club Band.mp3 in service mpd Feb 23 13:55:36 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=949&web=Beatles/Sgt.%20Pepper's%20Lonely%20Hearts%20Club%20Band/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FB%2FBeatles%20-%20Sgt_Pepper's_Lonely_Hearts_Club_Band%2FBeatles%20-%20Sgt%20Pepper's%20Lonely%20Hearts%20Club%20Band.mp3&metadata=false Feb 23 13:55:36 volumio volumio[1329]: info: URI /mnt/INTERNAL/Music/B/Beatles - Sgt_Pepper's_Lonely_Hearts_Club_Band/Beatles - Sgt Pepper's Lonely Hearts Club Band.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Exploding uri music-library/INTERNAL/Music/B/Beach Boys - Surfs Up/1._Beach_Boys_-_Surf's_Up_-_Don't_Go_Near_The_Water.mp3 in service mpd Feb 23 13:55:36 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=949&web=Beach%20Boys/Surf's%20Up/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FB%2FBeach%20Boys%20-%20Surfs%20Up%2F1._Beach_Boys_-_Surf's_Up_-_Don't_Go_Near_The_Water.mp3&metadata=false Feb 23 13:55:36 volumio volumio[1329]: info: URI /mnt/INTERNAL/Music/B/Beach Boys - Surfs Up/1._Beach_Boys_-_Surf's_Up_-_Don't_Go_Near_The_Water.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Exploding uri music-library/INTERNAL/Music/A/ACDC - High Voltage/ACDC_-_01_-_Its_A_Long_Way_To_The_Top.mp3 in service mpd Feb 23 13:55:36 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=949&web=ACDC/High%20Voltage/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FA%2FACDC%20-%20High%20Voltage%2FACDC_-_01_-_Its_A_Long_Way_To_The_Top.mp3&metadata=false Feb 23 13:55:36 volumio volumio[1329]: info: URI /mnt/INTERNAL/Music/A/ACDC - High Voltage/ACDC_-_01_-_Its_A_Long_Way_To_The_Top.mp3 Feb 23 13:55:36 volumio volumio[1329]: info: Exploding uri https://stream.radioparadise.com/rock-flacm in service radio_paradise Feb 23 13:55:36 volumio volumio[1329]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/rock-flacm" Feb 23 13:55:36 volumio volumio[1329]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:55:36 volumio volumio[1329]: TypeError: Cannot read properties of undefined (reading 'then') Feb 23 13:55:36 volumio volumio[1329]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Feb 23 13:55:36 volumio volumio[1329]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Feb 23 13:55:36 volumio volumio[1329]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Feb 23 13:55:36 volumio volumio[1329]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Feb 23 13:55:36 volumio volumio[1329]: at listOnTimeout (node:internal/timers:573:17) Feb 23 13:55:36 volumio volumio[1329]: at process.processTimers (node:internal/timers:514:7) Feb 23 13:55:36 volumio volumio[1329]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:55:38 volumio sudo[17473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 13:54' Feb 23 13:55:38 volumio sudo[17473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"