-- Logs begin at Thu 2025-03-13 18:35:50 UTC, end at Thu 2025-03-13 18:39:49 UTC. -- Mar 13 18:38:00 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:01 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:02 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:03 volumio volumio[1042]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 13 18:38:04 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:05 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:06 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:07 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:08 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:09 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:10 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:11 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:12 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:13 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:14 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:15 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:16 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:17 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:18 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:19 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:20 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:21 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:22 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:23 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:24 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:25 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:26 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:27 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:28 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:29 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:30 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:31 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:32 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:33 volumio volumio[1042]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 13 18:38:33 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 18:38:34 volumio volumio[1042]: info: CURURI: music-library Mar 13 18:38:34 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:34 volumio volumio[1042]: info: Mar 13 18:38:34 volumio volumio[1042]: ---------------------------- MPD announces state update: update Mar 13 18:38:34 volumio volumio[1042]: info: sending command... Mar 13 18:38:34 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:34 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:34 volumio volumio[1042]: info: sending command... Mar 13 18:38:34 volumio volumio[1042]: info: parsing response... Mar 13 18:38:34 volumio volumio[1042]: info: parsing response... Mar 13 18:38:34 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:34 volumio volumio[1042]: info: Command Router : Notfying DB Updatefalse Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::Close All Modals sent Mar 13 18:38:34 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:34 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:34 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:34 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:34 volumio volumio[1042]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 13 18:38:34 volumio volumio[1042]: info: CURRENT POSITION 0 Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::syncState stateService stop Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:34 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:34 volumio volumio[1042]: info: No code Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:34 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:34 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:34 volumio volumio[1042]: info: ------------------------------ 283ms Mar 13 18:38:34 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:35 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 18:38:35 volumio volumio[1042]: info: CURURI: music-library/USB Mar 13 18:38:35 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:36 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 18:38:36 volumio volumio[1042]: info: CURURI: music-library/USB/888E71818E716898 Mar 13 18:38:36 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:38 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:39 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:40 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:41 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:42 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::volumioAddQueueItems Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::addQueueItems Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::addQueueItems Mar 13 18:38:43 volumio volumio[1042]: info: Adding Item to queue: music-library/USB/888E71818E716898/Cold Spring Harbor Mar 13 18:38:43 volumio volumio[1042]: info: Exploding uri music-library/USB/888E71818E716898/Cold Spring Harbor in service mpd Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F01%20She's%20Got%20A%20Way.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/01 She's Got A Way.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F02%20You%20Can%20Make%20Me%20Free.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/02 You Can Make Me Free.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F03%20Everybody%20Loves%20You%20Now.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/03 Everybody Loves You Now.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F04%20Why%20Judy%20Why.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/04 Why Judy Why.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F05%20Falling%20Of%20The%20Rain.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/05 Falling Of The Rain.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F06%20Turn%20Around.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/06 Turn Around.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F07%20You%20Look%20So%20Good%20To%20Me.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/07 You Look So Good To Me.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F08%20Tomorrow%20Is%20Today.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/08 Tomorrow Is Today.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F09%20Nocturne.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/09 Nocturne.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2FCold%20Spring%20Harbor%2F10%20Got%20To%20Begin%20Again.mp3&metadata=false Mar 13 18:38:43 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/Cold Spring Harbor/10 Got To Begin Again.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::saveQueue Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::volumioPushQueue Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::volumioPlay Mar 13 18:38:43 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::play index 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:43 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/Cold Spring Harbor/01 She's Got A Way.mp3 Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: Mar 13 18:38:43 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:43 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/Cold Spring Harbor/01 She's Got A Way.mp3" Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: Mar 13 18:38:43 volumio volumio[1042]: info: Mar 13 18:38:43 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:43 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:43 volumio volumio[1042]: info: ------------------------------ 14ms Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: ------------------------------ 20ms Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:43 volumio volumio[1042]: info: Mar 13 18:38:43 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: Mar 13 18:38:43 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"She's Got A Way","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/01 She's Got A Way.mp3","trackType":"mp3"} Mar 13 18:38:43 volumio volumio[1042]: info: CURRENT POSITION 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:43 volumio volumio[1042]: info: ------------------------------ 102ms Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:43 volumio volumio[1042]: info: sending command... Mar 13 18:38:43 volumio volumio[1042]: info: parsing response... Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:43 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":175,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"She's Got A Way","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/01 She's Got A Way.mp3","trackType":"mp3"} Mar 13 18:38:43 volumio volumio[1042]: info: CURRENT POSITION 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:38:43 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:43 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:43 volumio volumio[1042]: info: ------------------------------ 255ms Mar 13 18:38:44 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:45 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:46 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:46 volumio volumio[1042]: info: CoreCommandRouter::volumioNext Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::next Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:46 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:46 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:46 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:46 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:38:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 0 Mar 13 18:38:46 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:38:46 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:38:46 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:46 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/Cold Spring Harbor/02 You Can Make Me Free.mp3 Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:47 volumio volumio[1042]: info: Mar 13 18:38:47 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: Mar 13 18:38:47 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:47 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/Cold Spring Harbor/02 You Can Make Me Free.mp3" Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: Mar 13 18:38:47 volumio volumio[1042]: info: Mar 13 18:38:47 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:47 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:47 volumio volumio[1042]: info: ------------------------------ 13ms Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::pushError Mar 13 18:38:47 volumio volumio[1042]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:38:47 volumio volumio[1042]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:38:47 volumio volumio[1042]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:38:47 volumio volumio[1042]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:38:47 volumio volumio[1042]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: ------------------------------ 42ms Mar 13 18:38:47 volumio volumio[1042]: info: ------------------------------ 24ms Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: Mar 13 18:38:47 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: Mar 13 18:38:47 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"You Can Make Me Free","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/02 You Can Make Me Free.mp3","trackType":"mp3"} Mar 13 18:38:47 volumio volumio[1042]: info: CURRENT POSITION 1 Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:47 volumio volumio[1042]: info: ------------------------------ 83ms Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:47 volumio volumio[1042]: info: sending command... Mar 13 18:38:47 volumio volumio[1042]: info: parsing response... Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:47 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":58,"duration":178,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"You Can Make Me Free","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/02 You Can Make Me Free.mp3","trackType":"mp3"} Mar 13 18:38:47 volumio volumio[1042]: info: CURRENT POSITION 1 Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:38:47 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:47 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:47 volumio volumio[1042]: info: ------------------------------ 270ms Mar 13 18:38:48 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::volumioNext Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::next Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:48 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 1 Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: Mar 13 18:38:48 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/Cold Spring Harbor/03 Everybody Loves You Now.mp3 Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: Mar 13 18:38:48 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:48 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/Cold Spring Harbor/03 Everybody Loves You Now.mp3" Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"You Can Make Me Free","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/02 You Can Make Me Free.mp3","trackType":"mp3"} Mar 13 18:38:48 volumio volumio[1042]: info: CURRENT POSITION 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::syncState stateService stop Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:48 volumio volumio[1042]: info: No code Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: ------------------------------ 179ms Mar 13 18:38:48 volumio volumio[1042]: info: Mar 13 18:38:48 volumio volumio[1042]: info: Mar 13 18:38:48 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:48 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:48 volumio volumio[1042]: info: ------------------------------ 147ms Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: ------------------------------ 34ms Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: Mar 13 18:38:48 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: Mar 13 18:38:48 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Everybody Loves You Now","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/03 Everybody Loves You Now.mp3","trackType":"mp3"} Mar 13 18:38:48 volumio volumio[1042]: info: CURRENT POSITION 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:48 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:48 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:48 volumio volumio[1042]: info: ------------------------------ 80ms Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:48 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:48 volumio volumio[1042]: info: sending command... Mar 13 18:38:48 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:49 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":169,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Everybody Loves You Now","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/03 Everybody Loves You Now.mp3","trackType":"mp3"} Mar 13 18:38:49 volumio volumio[1042]: info: CURRENT POSITION 2 Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:38:49 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:49 volumio volumio[1042]: info: ------------------------------ 253ms Mar 13 18:38:49 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::volumioNext Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::next Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:49 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 2 Mar 13 18:38:49 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/Cold Spring Harbor/04 Why Judy Why.mp3 Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:49 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:49 volumio volumio[1042]: info: Mar 13 18:38:49 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: Mar 13 18:38:49 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:49 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/Cold Spring Harbor/04 Why Judy Why.mp3" Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: Mar 13 18:38:49 volumio volumio[1042]: info: Mar 13 18:38:49 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:49 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:49 volumio volumio[1042]: info: ------------------------------ 15ms Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:38:49 volumio volumio[1042]: info: ControllerMpd::pushError Mar 13 18:38:49 volumio volumio[1042]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:38:49 volumio volumio[1042]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:38:49 volumio volumio[1042]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:38:49 volumio volumio[1042]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:38:49 volumio volumio[1042]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:38:49 volumio volumio[1042]: info: sending command... Mar 13 18:38:49 volumio volumio[1042]: info: ------------------------------ 38ms Mar 13 18:38:49 volumio volumio[1042]: info: ------------------------------ 16ms Mar 13 18:38:49 volumio volumio[1042]: info: parsing response... Mar 13 18:38:50 volumio volumio[1042]: info: Mar 13 18:38:50 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:50 volumio volumio[1042]: info: sending command... Mar 13 18:38:50 volumio volumio[1042]: info: parsing response... Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:50 volumio volumio[1042]: info: sending command... Mar 13 18:38:50 volumio volumio[1042]: info: Mar 13 18:38:50 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:50 volumio volumio[1042]: info: parsing response... Mar 13 18:38:50 volumio volumio[1042]: info: sending command... Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:50 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:50 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":177,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Why Judy Why","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/04 Why Judy Why.mp3","trackType":"mp3"} Mar 13 18:38:50 volumio volumio[1042]: info: CURRENT POSITION 3 Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:50 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:50 volumio volumio[1042]: info: ------------------------------ 206ms Mar 13 18:38:50 volumio volumio[1042]: info: parsing response... Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:50 volumio volumio[1042]: info: sending command... Mar 13 18:38:50 volumio volumio[1042]: info: parsing response... Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:50 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:50 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:50 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":69,"duration":177,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Why Judy Why","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/04 Why Judy Why.mp3","trackType":"mp3"} Mar 13 18:38:50 volumio volumio[1042]: info: CURRENT POSITION 3 Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:38:50 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:50 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:50 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:50 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:50 volumio volumio[1042]: info: ------------------------------ 308ms Mar 13 18:38:50 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::volumioNext Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::next Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:51 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 3 Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/Cold Spring Harbor/05 Falling Of The Rain.mp3 Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:38:51 volumio volumio[1042]: info: Mar 13 18:38:51 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: Mar 13 18:38:51 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:51 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/Cold Spring Harbor/05 Falling Of The Rain.mp3" Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: Mar 13 18:38:51 volumio volumio[1042]: info: Mar 13 18:38:51 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:38:51 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:38:51 volumio volumio[1042]: info: ------------------------------ 15ms Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::pushError Mar 13 18:38:51 volumio volumio[1042]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:38:51 volumio volumio[1042]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:38:51 volumio volumio[1042]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:38:51 volumio volumio[1042]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:38:51 volumio volumio[1042]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: ------------------------------ 36ms Mar 13 18:38:51 volumio volumio[1042]: info: ------------------------------ 14ms Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:51 volumio volumio[1042]: info: Mar 13 18:38:51 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: Mar 13 18:38:51 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":162,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Falling Of The Rain","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/05 Falling Of The Rain.mp3","trackType":"mp3"} Mar 13 18:38:51 volumio volumio[1042]: info: CURRENT POSITION 4 Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:51 volumio volumio[1042]: info: ------------------------------ 89ms Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:38:51 volumio volumio[1042]: info: sending command... Mar 13 18:38:51 volumio volumio[1042]: info: parsing response... Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:38:51 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":162,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Falling Of The Rain","artist":"Billy Joel","album":"Cold Spring Harbor","uri":"USB/888E71818E716898/Cold Spring Harbor/05 Falling Of The Rain.mp3","trackType":"mp3"} Mar 13 18:38:51 volumio volumio[1042]: info: CURRENT POSITION 4 Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:38:51 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:38:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:38:51 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:38:51 volumio volumio[1042]: info: ------------------------------ 182ms Mar 13 18:38:52 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:53 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:54 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:55 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:56 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:57 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:58 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:38:59 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:01 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:02 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:03 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:03 volumio volumio[1042]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioAddQueueItems Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::addQueueItems Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::addQueueItems Mar 13 18:39:04 volumio volumio[1042]: info: Adding Item to queue: music-library/USB/888E71818E716898/2000 Global Spirit Mar 13 18:39:04 volumio volumio[1042]: info: Exploding uri music-library/USB/888E71818E716898/2000 Global Spirit in service mpd Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F01-Call%20Of%20The%20Tribes%20(Remix).mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/01-Call Of The Tribes (Remix).mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F02-Ancient%20Secrets.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/02-Ancient Secrets.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F03-Punjab.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F04-Native%20Rituals.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/04-Native Rituals.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F05-Kubula%20Ma.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/05-Kubula Ma.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F06-Alibaba.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/06-Alibaba.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F07-Earthsong.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/07-Earthsong.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F08-Bombay%20Pure.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/08-Bombay Pure.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F09-Solitude.mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/09-Solitude.mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ALBUMART /albumart?cacheid=264&path=%2Fmnt%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit%2F10-Earthsong%20(Reprise).mp3&metadata=false Mar 13 18:39:04 volumio volumio[1042]: info: URI /mnt/USB/888E71818E716898/2000 Global Spirit/10-Earthsong (Reprise).mp3 Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::saveQueue Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushQueue Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPlay Mar 13 18:39:04 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::play index 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:04 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 4 Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/2000 Global Spirit/01-Call Of The Tribes (Remix).mp3 Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: Mar 13 18:39:04 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: Mar 13 18:39:04 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:39:04 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/2000 Global Spirit/01-Call Of The Tribes (Remix).mp3" Mar 13 18:39:04 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: Mar 13 18:39:04 volumio volumio[1042]: info: Mar 13 18:39:04 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:39:04 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:39:04 volumio volumio[1042]: info: ------------------------------ 17ms Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::pushError Mar 13 18:39:04 volumio volumio[1042]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:39:04 volumio volumio[1042]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:39:04 volumio volumio[1042]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:39:04 volumio volumio[1042]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:39:04 volumio volumio[1042]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: ------------------------------ 40ms Mar 13 18:39:04 volumio volumio[1042]: info: ------------------------------ 17ms Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: Mar 13 18:39:04 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: Mar 13 18:39:04 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:04 volumio volumio[1042]: info: sending command... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Call Of The Tribes (Remix)","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/01-Call Of The Tribes (Remix).mp3","trackType":"mp3"} Mar 13 18:39:04 volumio volumio[1042]: info: CURRENT POSITION 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:04 volumio volumio[1042]: info: ------------------------------ 207ms Mar 13 18:39:04 volumio volumio[1042]: info: parsing response... Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:04 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":279,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Call Of The Tribes (Remix)","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/01-Call Of The Tribes (Remix).mp3","trackType":"mp3"} Mar 13 18:39:04 volumio volumio[1042]: info: CURRENT POSITION 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:39:04 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:04 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:04 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:04 volumio volumio[1042]: info: ------------------------------ 180ms Mar 13 18:39:05 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:06 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::volumioNext Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::next Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:07 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 10 Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/2000 Global Spirit/02-Ancient Secrets.mp3 Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:07 volumio volumio[1042]: info: Mar 13 18:39:07 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: Mar 13 18:39:07 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:39:07 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/2000 Global Spirit/02-Ancient Secrets.mp3" Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: Mar 13 18:39:07 volumio volumio[1042]: info: ------------------------------ 13ms Mar 13 18:39:07 volumio volumio[1042]: info: Mar 13 18:39:07 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:39:07 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::pushError Mar 13 18:39:07 volumio volumio[1042]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:39:07 volumio volumio[1042]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:39:07 volumio volumio[1042]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:39:07 volumio volumio[1042]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:39:07 volumio volumio[1042]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: ------------------------------ 39ms Mar 13 18:39:07 volumio volumio[1042]: info: ------------------------------ 17ms Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: Mar 13 18:39:07 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: Mar 13 18:39:07 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Ancient Secrets","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/02-Ancient Secrets.mp3","trackType":"mp3"} Mar 13 18:39:07 volumio volumio[1042]: info: CURRENT POSITION 11 Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:07 volumio volumio[1042]: info: sending command... Mar 13 18:39:07 volumio volumio[1042]: info: ------------------------------ 206ms Mar 13 18:39:07 volumio volumio[1042]: info: parsing response... Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:07 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":298,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Ancient Secrets","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/02-Ancient Secrets.mp3","trackType":"mp3"} Mar 13 18:39:07 volumio volumio[1042]: info: CURRENT POSITION 11 Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:39:07 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:07 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:07 volumio volumio[1042]: info: ------------------------------ 287ms Mar 13 18:39:08 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::volumioNext Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::next Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:08 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 11 Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:08 volumio volumio[1042]: info: sending command... Mar 13 18:39:08 volumio volumio[1042]: info: Mar 13 18:39:08 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:08 volumio volumio[1042]: info: parsing response... Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:08 volumio volumio[1042]: info: sending command... Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3 Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:08 volumio volumio[1042]: info: sending command... Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:08 volumio volumio[1042]: info: parsing response... Mar 13 18:39:08 volumio volumio[1042]: info: parsing response... Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:39:08 volumio volumio[1042]: info: sending command... Mar 13 18:39:08 volumio volumio[1042]: info: sending command... Mar 13 18:39:08 volumio volumio[1042]: info: Mar 13 18:39:08 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:39:08 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:39:08 volumio volumio[1042]: info: parsing response... Mar 13 18:39:08 volumio volumio[1042]: info: parsing response... Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3" Mar 13 18:39:08 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:08 volumio volumio[1042]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Ancient Secrets","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/02-Ancient Secrets.mp3","trackType":"mp3"} Mar 13 18:39:08 volumio volumio[1042]: info: CURRENT POSITION 12 Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::syncState stateService stop Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:08 volumio volumio[1042]: info: No code Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:08 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:08 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:08 volumio volumio[1042]: info: sending command... Mar 13 18:39:08 volumio volumio[1042]: info: ------------------------------ 166ms Mar 13 18:39:08 volumio volumio[1042]: info: Mar 13 18:39:08 volumio volumio[1042]: info: Mar 13 18:39:08 volumio volumio[1042]: ---------------------------- MPD announces system playlist update Mar 13 18:39:08 volumio volumio[1042]: info: Ignoring MPD Status Update Mar 13 18:39:09 volumio volumio[1042]: info: ------------------------------ 138ms Mar 13 18:39:09 volumio volumio[1042]: info: parsing response... Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand play Mar 13 18:39:09 volumio volumio[1042]: info: sending command... Mar 13 18:39:09 volumio volumio[1042]: info: ------------------------------ 24ms Mar 13 18:39:09 volumio volumio[1042]: info: parsing response... Mar 13 18:39:09 volumio volumio[1042]: info: Mar 13 18:39:09 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:09 volumio volumio[1042]: info: sending command... Mar 13 18:39:09 volumio volumio[1042]: info: Mar 13 18:39:09 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:09 volumio volumio[1042]: info: parsing response... Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:09 volumio volumio[1042]: info: sending command... Mar 13 18:39:09 volumio volumio[1042]: info: sending command... Mar 13 18:39:09 volumio volumio[1042]: info: parsing response... Mar 13 18:39:09 volumio volumio[1042]: info: parsing response... Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::parseState Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:09 volumio volumio[1042]: info: sending command... Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:09 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:09 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":434,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Punjab","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3","trackType":"mp3"} Mar 13 18:39:09 volumio volumio[1042]: info: CURRENT POSITION 12 Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:09 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:09 volumio volumio[1042]: info: ------------------------------ 82ms Mar 13 18:39:09 volumio volumio[1042]: info: parsing response... Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::parseTrackInfo Mar 13 18:39:09 volumio volumio[1042]: info: ControllerMpd::pushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::servicePushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::syncState Mar 13 18:39:09 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:09 volumio volumio[1042]: info: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":434,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Punjab","artist":"Karunesh","album":"Global Spirit","uri":"USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3","trackType":"mp3"} Mar 13 18:39:09 volumio volumio[1042]: info: CURRENT POSITION 12 Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:39:09 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:09 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:09 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:09 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:09 volumio volumio[1042]: info: ------------------------------ 258ms Mar 13 18:39:09 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:10 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:10 volumio volumio[1042]: info: VolumeController::SetAlsaVolume39 Mar 13 18:39:10 volumio volumio[1042]: Error: Alsa Mixer Error: amixer: Mixer hw:1 load error: Input/output error Mar 13 18:39:10 volumio volumio[1042]: at Socket. (/volumio/app/volumecontrol.js:72:10) Mar 13 18:39:10 volumio volumio[1042]: at emitOne (events.js:116:13) Mar 13 18:39:10 volumio volumio[1042]: at Socket.emit (events.js:211:7) Mar 13 18:39:10 volumio volumio[1042]: at addChunk (_stream_readable.js:263:12) Mar 13 18:39:10 volumio volumio[1042]: at readableAddChunk (_stream_readable.js:250:11) Mar 13 18:39:10 volumio volumio[1042]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 13 18:39:10 volumio volumio[1042]: at Pipe.onread (net.js:607:20) Mar 13 18:39:10 volumio volumio[1042]: info: VolumeController::Volume 39 Mar 13 18:39:10 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:10 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:10 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:10 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:10 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:11 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:12 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:13 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 18:39:13 volumio volumio[1042]: amixer: Mixer hw:1 load error: Input/output error Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:13 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:14 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:15 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:16 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:17 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:18 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:19 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:20 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:21 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:23 volumio volumio[1186]: Wrong scontrol identifier: Mar 13 18:39:23 volumio volumio[1042]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 13 18:39:23 volumio volumio[1042]: info: Enable softmixer device for audio device number 1 Mar 13 18:39:23 volumio volumio[1042]: info: Enable softmixer device for audio device number 1 Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::volumioStop Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::stop Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:23 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::updateTrackBlock Mar 13 18:39:23 volumio volumio[1042]: info: CorePlayQueue::getTrackBlock Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:23 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:23 volumio volumio[1042]: info: CoreStateMachine::serviceStop Mar 13 18:39:23 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::serviceStop Mar 13 18:39:23 volumio volumio[1042]: info: ControllerMpd::stop Mar 13 18:39:23 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:23 volumio volumio[1042]: info: Volume configurations have been set Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 13 18:39:23 volumio volumio[1042]: info: Updating Volume Controller Parameters: Device: 1 Name: I-Sabre K2M DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 13 18:39:23 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 18:39:23 volumio volumio[1042]: info: sending command... Mar 13 18:39:23 volumio volumio[1042]: info: Asound.conf file written Mar 13 18:39:23 volumio sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 13 18:39:23 volumio sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:39:23 volumio sudo[2242]: pam_unix(sudo:session): session closed for user root Mar 13 18:39:23 volumio volumio[1042]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Input/output error Mar 13 18:39:23 volumio volumio[1042]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Mute Switch:0' : Input/output error Mar 13 18:39:24 volumio volumio[1042]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 13 18:39:24 volumio volumio[1042]: Found hardware: "I-Sabre_K2M_DAC" "" "" "" "" Mar 13 18:39:24 volumio volumio[1042]: Hardware is initialized using a generic method Mar 13 18:39:24 volumio volumio[1042]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Input/output error Mar 13 18:39:24 volumio volumio[1042]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Mute Switch:0' : Input/output error Mar 13 18:39:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:24 volumio volumio[1042]: info: Output device has changed, restarting MPD Mar 13 18:39:24 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 18:39:24 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync Mar 13 18:39:24 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:24 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:39:24 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root Mar 13 18:39:24 volumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 18:39:25 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:39:25 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 13 18:39:25 volumio volumio[1042]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 13 18:39:25 volumio volumio[1042]: info: Mar 13 18:39:25 volumio volumio[1042]: ---------------------------- MPD announces state update: player Mar 13 18:39:25 volumio volumio[1042]: info: parsing response... Mar 13 18:39:25 volumio volumio[1042]: info: ControllerMpd::getState Mar 13 18:39:25 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand status Mar 13 18:39:25 volumio volumio[1042]: info: sending command... Mar 13 18:39:25 volumio volumio[1042]: info: MPD Permissions set Mar 13 18:39:25 volumio volumio[1042]: error: MPD error: Error: write EPIPE Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 18:39:25 volumio systemd[1]: mpd.service: main process exited, code=exited, status=1/FAILURE Mar 13 18:39:25 volumio systemd[1]: Unit mpd.service entered failed state. Mar 13 18:39:25 volumio systemd[1]: Starting Music Player Daemon... Mar 13 18:39:25 volumio systemd[1]: Started Music Player Daemon. Mar 13 18:39:25 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Mar 13 18:39:25 volumio volumio[1042]: amixer: Mixer hw:1 load error: Input/output error Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:39:25 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 13 18:39:25 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:39:25 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Mar 13 18:39:25 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Mar 13 18:39:25 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Mar 13 18:39:25 volumio systemd[1]: Started ShairportSync AirTunes receiver. Mar 13 18:39:25 volumio shairport-sync[2294]: Successful Startup Mar 13 18:39:25 volumio volumio[1042]: info: [1741891165385] Shairport-Sync Started Mar 13 18:39:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 13 18:39:26 volumio sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 18:39:26 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:39:26 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 18:39:30 volumio volumio[1042]: info: CoreCommandRouter::volumioAddQueueItems Mar 13 18:39:30 volumio volumio[1042]: info: CoreStateMachine::addQueueItems Mar 13 18:39:30 volumio volumio[1042]: info: CorePlayQueue::addQueueItems Mar 13 18:39:30 volumio volumio[1042]: info: Adding Item to queue: music-library/USB/888E71818E716898/2000 Global Spirit Mar 13 18:39:30 volumio volumio[1042]: info: Exploding uri music-library/USB/888E71818E716898/2000 Global Spirit in service mpd Mar 13 18:39:33 volumio volumio[1042]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 13 18:39:35 volumio volumio[1042]: info: CoreCommandRouter::volumioPlay Mar 13 18:39:35 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:35 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:39:35 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:35 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:35 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:39:35 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:35 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3 Mar 13 18:39:35 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:40 volumio volumio[1042]: info: CoreCommandRouter::volumioPlay Mar 13 18:39:40 volumio volumio[1042]: UNSET VOLATILE Mar 13 18:39:40 volumio volumio[1042]: info: CoreStateMachine::play index undefined Mar 13 18:39:40 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:39:40 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:40 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:39:40 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:40 volumio volumio[1042]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3 Mar 13 18:39:40 volumio volumio[1042]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:39:42 volumio volumio[1042]: error: MPD error: Error: read ECONNRESET Mar 13 18:39:42 volumio systemd[1]: mpd.service: main process exited, code=exited, status=1/FAILURE Mar 13 18:39:42 volumio systemd[1]: Unit mpd.service entered failed state. Mar 13 18:39:42 volumio systemd[1]: Starting Music Player Daemon... Mar 13 18:39:42 volumio systemd[1]: Started Music Player Daemon. Mar 13 18:39:42 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root Mar 13 18:39:43 volumio volumio[1042]: info: VolumeController::SetAlsaVolume51 Mar 13 18:39:43 volumio volumio[1042]: Error: Alsa Mixer Error: amixer: Mixer hw:1 load error: Input/output error Mar 13 18:39:43 volumio volumio[1042]: at Socket. (/volumio/app/volumecontrol.js:72:10) Mar 13 18:39:43 volumio volumio[1042]: at emitOne (events.js:116:13) Mar 13 18:39:43 volumio volumio[1042]: at Socket.emit (events.js:211:7) Mar 13 18:39:43 volumio volumio[1042]: at addChunk (_stream_readable.js:263:12) Mar 13 18:39:43 volumio volumio[1042]: at readableAddChunk (_stream_readable.js:250:11) Mar 13 18:39:43 volumio volumio[1042]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 13 18:39:43 volumio volumio[1042]: at Pipe.onread (net.js:607:20) Mar 13 18:39:43 volumio volumio[1042]: info: VolumeController::Volume 51 Mar 13 18:39:43 volumio volumio[1042]: info: CoreStateMachine::pushState Mar 13 18:39:43 volumio volumio[1042]: info: CoreStateMachine::getState Mar 13 18:39:43 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:43 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState Mar 13 18:39:43 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:39:47 volumio volumio[1042]: info: CoreCommandRouter::volumioSeek Mar 13 18:39:47 volumio volumio[1042]: info: CoreStateMachine::seek Mar 13 18:39:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:47 volumio volumio[1042]: info: TRACKBLOCK {"uri":"mnt/USB/888E71818E716898/2000 Global Spirit/03-Punjab.mp3","service":"mpd","name":"Punjab","artist":"Karunesh","album":"Global Spirit","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=264&path=%2FUSB%2F888E71818E716898%2F2000%20Global%20Spirit&metadata=false","duration":434,"samplerate":"44.1 KHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Mar 13 18:39:47 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:39:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 12 Mar 13 18:39:47 volumio volumio[1042]: info: ControllerMpd::seek Mar 13 18:39:47 volumio volumio[1042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 18:39:47 volumio volumio[1042]: { AssertionError [ERR_ASSERTION]: false == true Mar 13 18:39:47 volumio volumio[1042]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Mar 13 18:39:47 volumio volumio[1042]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2725:24) Mar 13 18:39:47 volumio volumio[1042]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1181:15) Mar 13 18:39:47 volumio volumio[1042]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1312:27) Mar 13 18:39:47 volumio volumio[1042]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) Mar 13 18:39:47 volumio volumio[1042]: at emitTwo (events.js:126:13) Mar 13 18:39:47 volumio volumio[1042]: at Socket.emit (events.js:214:7) Mar 13 18:39:47 volumio volumio[1042]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 13 18:39:47 volumio volumio[1042]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:39:47 volumio volumio[1042]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:39:47 volumio volumio[1042]: generatedMessage: true, Mar 13 18:39:47 volumio volumio[1042]: name: 'AssertionError [ERR_ASSERTION]', Mar 13 18:39:47 volumio volumio[1042]: code: 'ERR_ASSERTION', Mar 13 18:39:47 volumio volumio[1042]: actual: false, Mar 13 18:39:47 volumio volumio[1042]: expected: true, Mar 13 18:39:47 volumio volumio[1042]: operator: '==' } Mar 13 18:39:47 volumio volumio[1042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 18:39:49 volumio sudo[2331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 18:38 Mar 13 18:39:49 volumio sudo[2331]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="7124eaf0213deb060cebb17ef631669189a6650e" VOLUMIO_FE_VERSION="b52b5dfa93e3b26d6f23f09c91a5b90b0a43edf0" VOLUMIO_BE_VERSION="273d2050e44b6f5ae901d967e346577f7ad86f2c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Aug 27 17:21:50 CEST 2018" VOLUMIO_VERSION="2.452" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1802c403029d39af3072f40e987e7ba4"