-- Logs begin at Thu 2025-03-13 18:14:38 UTC, end at Thu 2025-03-13 18:24:04 UTC. -- Mar 13 18:23:12 volumio volumio[1046]: info: [1741890192489] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioNext Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::next Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::stop Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:23:13 volumio volumio[1046]: UNSET VOLATILE Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 275 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::serviceStop Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 275 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::serviceStop Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::stop Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::play index undefined Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:13 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso" Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushError Mar 13 18:23:13 volumio volumio[1046]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:23:13 volumio volumio[1046]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:23:13 volumio volumio[1046]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:23:13 volumio volumio[1046]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:23:13 volumio volumio[1046]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushError Mar 13 18:23:13 volumio volumio[1046]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:23:13 volumio volumio[1046]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:23:13 volumio volumio[1046]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:23:13 volumio volumio[1046]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:23:13 volumio volumio[1046]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 69ms Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 67ms Mar 13 18:23:13 volumio volumio[1046]: info: [1741890193311] [RadioParadise] received new metadata: {"time":126,"artist":"Fats Domino","title":"Walking to New Orleans","album":"Antoine","year":"1960","cover":"https://img.radioparadise.com/covers/l/B0000032FC.jpg","cover_med":"https://img.radioparadise.com/covers/m/B0000032FC.jpg","cover_small":"https://img.radioparadise.com/covers/s/B0000032FC.jpg"} Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received radio_paradise Mar 13 18:23:13 volumio volumio[1046]: info: [1741890193316] [RadioParadise] setting new timer with duration of 131000 seconds. Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:13 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:13 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:13 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 37ms Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 11ms Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 9ms Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand play Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushError Mar 13 18:23:13 volumio volumio[1046]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:23:13 volumio volumio[1046]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:23:13 volumio volumio[1046]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:23:13 volumio volumio[1046]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:23:13 volumio volumio[1046]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 79ms Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:13 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:13 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 20ms Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 9ms Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 7ms Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: Mar 13 18:23:13 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:13 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:13 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:13 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:13 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:13 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: sending command... Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 409ms Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 408ms Mar 13 18:23:13 volumio volumio[1046]: info: ------------------------------ 407ms Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: parsing response... Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":17,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:13 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:13 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:13 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:13 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:13 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:13 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:14 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:14 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:14 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:14 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":52,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:14 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:14 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:14 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:14 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:14 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:14 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:14 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:14 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:14 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:14 volumio volumio[1046]: info: ------------------------------ 660ms Mar 13 18:23:14 volumio volumio[1046]: info: ------------------------------ 645ms Mar 13 18:23:14 volumio volumio[1046]: info: ------------------------------ 642ms Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::volumioSeek Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::seek Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: TRACKBLOCK {"uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","service":"mpd","name":"Walking to New Orleans","artist":"Fats Domino","album":"Antoine","type":"track","tracknumber":0,"albumart":"https://img.radioparadise.com/covers/l/B0000032FC.jpg","duration":408,"samplerate":"44.1 KHz","bitdepth":"16 bit","trackType":"Rparadise Mellow","channels":2} Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::seek Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:20 volumio volumio[1046]: info: Mar 13 18:23:20 volumio volumio[1046]: info: Mar 13 18:23:20 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:20 volumio volumio[1046]: info: sending command... Mar 13 18:23:20 volumio volumio[1046]: info: Mar 13 18:23:20 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:20 volumio volumio[1046]: info: sending command... Mar 13 18:23:20 volumio volumio[1046]: info: Mar 13 18:23:20 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:20 volumio volumio[1046]: info: sending command... Mar 13 18:23:20 volumio volumio[1046]: info: parsing response... Mar 13 18:23:20 volumio volumio[1046]: info: parsing response... Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:20 volumio volumio[1046]: info: sending command... Mar 13 18:23:20 volumio volumio[1046]: info: sending command... Mar 13 18:23:20 volumio volumio[1046]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 13 18:23:20 volumio volumio[1046]: info: parsing response... Mar 13 18:23:20 volumio volumio[1046]: info: parsing response... Mar 13 18:23:20 volumio volumio[1046]: info: parsing response... Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:20 volumio volumio[1046]: info: sending command... Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:20 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:20 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:20 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:20 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:20 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:20 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:20 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:20 volumio volumio[1046]: info: ------------------------------ 313ms Mar 13 18:23:21 volumio volumio[1046]: info: ------------------------------ 307ms Mar 13 18:23:21 volumio volumio[1046]: info: parsing response... Mar 13 18:23:21 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:21 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:21 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:21 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:21 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":64000,"duration":408,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 02 - PRIVATE INVESTIGATIONS","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK002.iso","trackType":"iso"} Mar 13 18:23:21 volumio volumio[1046]: info: CURRENT POSITION 276 Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:21 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:21 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:21 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:21 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:21 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:21 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:21 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:21 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:21 volumio volumio[1046]: info: ------------------------------ 419ms Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioNext Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::next Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::stop Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:23:28 volumio volumio[1046]: UNSET VOLATILE Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::serviceStop Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 276 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::serviceStop Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::stop Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::play index undefined Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::clearAddPlayTracks USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand stop Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand clear Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:28 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand add "USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso" Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: 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:23:28 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState stateService stop Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: No code Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: 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:23:28 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState stateService stop Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: No code Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 265ms Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 265ms Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:28 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:28 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:28 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 269ms Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 12ms Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 10ms Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand play Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::pushError Mar 13 18:23:28 volumio volumio[1046]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Mar 13 18:23:28 volumio volumio[1046]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Mar 13 18:23:28 volumio volumio[1046]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 13 18:23:28 volumio volumio[1046]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:23:28 volumio volumio[1046]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 296ms Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:28 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces system playlist update Mar 13 18:23:28 volumio volumio[1046]: info: Ignoring MPD Status Update Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 81ms Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 68ms Mar 13 18:23:28 volumio volumio[1046]: info: ------------------------------ 66ms Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: Mar 13 18:23:28 volumio volumio[1046]: ---------------------------- MPD announces state update: player Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::getState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand status Mar 13 18:23:28 volumio volumio[1046]: info: sending command... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: parsing response... Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::parseState Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 13 18:23:28 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:28 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:28 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","trackType":"iso"} Mar 13 18:23:28 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:28 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: sending command... Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","trackType":"iso"} Mar 13 18:23:29 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:29 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","trackType":"iso"} Mar 13 18:23:29 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:29 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: sending command... Mar 13 18:23:29 volumio volumio[1046]: info: sending command... Mar 13 18:23:29 volumio volumio[1046]: info: ------------------------------ 422ms Mar 13 18:23:29 volumio volumio[1046]: info: ------------------------------ 416ms Mar 13 18:23:29 volumio volumio[1046]: info: ------------------------------ 413ms Mar 13 18:23:29 volumio volumio[1046]: info: parsing response... Mar 13 18:23:29 volumio volumio[1046]: info: parsing response... Mar 13 18:23:29 volumio volumio[1046]: info: parsing response... Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::parseTrackInfo Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":351,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","trackType":"iso"} Mar 13 18:23:29 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:29 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":58,"duration":351,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","trackType":"iso"} Mar 13 18:23:29 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:29 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: ControllerMpd::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: STATE SERVICE {"status":"play","position":0,"seek":75,"duration":351,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","trackType":"iso"} Mar 13 18:23:29 volumio volumio[1046]: info: CURRENT POSITION 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState stateService play Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus play Mar 13 18:23:29 volumio volumio[1046]: info: Received an update from plugin. extracting info from payload Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreStateMachine::getState Mar 13 18:23:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 13 18:23:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 18:23:29 volumio volumio[1046]: info: ------------------------------ 688ms Mar 13 18:23:29 volumio volumio[1046]: info: ------------------------------ 665ms Mar 13 18:23:29 volumio volumio[1046]: info: ------------------------------ 658ms Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:38 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 18:23:50 volumio volumio[1046]: info: [jellyfin-poller-worker] _doPoll(): All servers polled. Will poll again in 30000ms. Mar 13 18:23:52 volumio volumio[1046]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Mar 13 18:23:52 volumio sudo[4519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio sudo[4519]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 18:23:52 volumio sudo[4519]: pam_unix(sudo:session): session closed for user root Mar 13 18:23:52 volumio volumio[1046]: info: MPD Permissions set Mar 13 18:23:52 volumio sudo[4523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 18:23:52 volumio sudo[4523]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 18:23:52 volumio systemd[1]: Stopping Music Player Daemon... Mar 13 18:23:52 volumio systemd[1]: mpd.service: main process exited, code=exited, status=1/FAILURE Mar 13 18:23:52 volumio systemd[1]: Unit mpd.service entered failed state. Mar 13 18:23:52 volumio systemd[1]: Starting Music Player Daemon... Mar 13 18:23:52 volumio systemd[1]: Started Music Player Daemon. Mar 13 18:23:52 volumio sudo[4523]: pam_unix(sudo:session): session closed for user root Mar 13 18:23:52 volumio volumio[1046]: error: Upnp client error: Error: This socket has been ended by the other party Mar 13 18:23:56 volumio volumio[1046]: info: CoreCommandRouter::volumioPause Mar 13 18:23:56 volumio volumio[1046]: info: CoreStateMachine::pause Mar 13 18:23:56 volumio volumio[1046]: info: CoreStateMachine::stPlaybackTimer Mar 13 18:23:56 volumio volumio[1046]: info: CoreStateMachine::servicePause Mar 13 18:23:56 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:23:56 volumio volumio[1046]: info: CoreCommandRouter::servicePause Mar 13 18:23:56 volumio volumio[1046]: info: ControllerMpd::pause Mar 13 18:23:56 volumio volumio[1046]: info: ControllerMpd::sendMpdCommand pause Mar 13 18:23:59 volumio volumio[1046]: info: CoreCommandRouter::volumioPause Mar 13 18:23:59 volumio volumio[1046]: info: CoreStateMachine::pause Mar 13 18:24:00 volumio volumio[1046]: info: CoreCommandRouter::volumioPause Mar 13 18:24:00 volumio volumio[1046]: info: CoreStateMachine::pause Mar 13 18:24:00 volumio volumio[1046]: info: CoreCommandRouter::volumioPause Mar 13 18:24:00 volumio volumio[1046]: info: CoreStateMachine::pause Mar 13 18:24:01 volumio volumio[1046]: info: CoreCommandRouter::volumioPause Mar 13 18:24:01 volumio volumio[1046]: info: CoreStateMachine::pause Mar 13 18:24:02 volumio volumio[1046]: info: CoreCommandRouter::volumioSeek Mar 13 18:24:02 volumio volumio[1046]: info: CoreStateMachine::seek Mar 13 18:24:02 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:24:02 volumio volumio[1046]: info: TRACKBLOCK {"uri":"USB/888E71818E716898/1982 - Love Over Gold (2011 SHM SACD-R)/LOVE OVER GOLD.iso/2C_AUDIO__TRACK003.iso","service":"mpd","name":"2CH - 03 - INDUSTRIAL DISEASE","artist":"DIRE STRAITS","album":"LOVE OVER GOLD (2CH-DSD)","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=264&path=%2FUSB%2F888E71818E716898%2F1982%20-%20Love%20Over%20Gold%20(2011%20SHM%20SACD-R)&metadata=false","duration":351,"samplerate":"","bitdepth":"","trackType":"iso","channels":2} Mar 13 18:24:02 volumio volumio[1046]: info: CoreStateMachine::startPlaybackTimer Mar 13 18:24:02 volumio volumio[1046]: info: CorePlayQueue::getTrack 277 Mar 13 18:24:02 volumio volumio[1046]: info: ControllerMpd::seek Mar 13 18:24:02 volumio volumio[1046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 18:24:02 volumio volumio[1046]: { AssertionError [ERR_ASSERTION]: false == true Mar 13 18:24:02 volumio volumio[1046]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Mar 13 18:24:02 volumio volumio[1046]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2725:24) Mar 13 18:24:02 volumio volumio[1046]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1181:15) Mar 13 18:24:02 volumio volumio[1046]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1312:27) Mar 13 18:24:02 volumio volumio[1046]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) Mar 13 18:24:02 volumio volumio[1046]: at emitTwo (events.js:126:13) Mar 13 18:24:02 volumio volumio[1046]: at Socket.emit (events.js:214:7) Mar 13 18:24:02 volumio volumio[1046]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 13 18:24:02 volumio volumio[1046]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 13 18:24:02 volumio volumio[1046]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 13 18:24:02 volumio volumio[1046]: generatedMessage: true, Mar 13 18:24:02 volumio volumio[1046]: name: 'AssertionError [ERR_ASSERTION]', Mar 13 18:24:02 volumio volumio[1046]: code: 'ERR_ASSERTION', Mar 13 18:24:02 volumio volumio[1046]: actual: false, Mar 13 18:24:02 volumio volumio[1046]: expected: true, Mar 13 18:24:02 volumio volumio[1046]: operator: '==' } Mar 13 18:24:02 volumio volumio[1046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 18:24:04 volumio sudo[4551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 18:23 Mar 13 18:24:04 volumio sudo[4551]: 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"