-- Logs begin at Mon 2024-08-19 18:08:07 UTC, end at Sun 2024-08-25 14:05:45 UTC. -- Aug 25 14:04:01 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:04:01 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:04:01 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:04:01 volumio volumio[909]: info: CorePlayQueue::getTrack 6 Aug 25 14:04:02 volumio volumio[909]: info: Aug 25 14:04:02 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:04:02 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:04:02 volumio volumio[909]: info: Aug 25 14:04:02 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:04:02 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:04:02 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:04:02 volumio volumio[909]: info: sending command... Aug 25 14:04:02 volumio volumio[909]: info: Aug 25 14:04:02 volumio volumio[909]: info: ------------------------------ 4ms Aug 25 14:04:02 volumio volumio[909]: info: parsing response... Aug 25 14:04:02 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:04:02 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:04:02 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:04:02 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:04:02 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:04:02 volumio volumio[909]: 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} Aug 25 14:04:02 volumio volumio[909]: info: CURRENT POSITION 5 Aug 25 14:04:02 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Aug 25 14:04:02 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:04:02 volumio volumio[909]: info: CURRENT POSITION 5 Aug 25 14:04:02 volumio volumio[909]: info: CURRENT POSITION 5 Aug 25 14:04:02 volumio volumio[909]: info: END OF QUEUE Aug 25 14:04:02 volumio volumio[909]: info: CoreStateMachine::pushEmptyState Aug 25 14:04:02 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:02 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:02 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:02 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Aug 25 14:04:02 volumio volumio[909]: info: ------------------------------ 29ms Aug 25 14:04:02 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Aug 25 14:04:08 volumio volumio[909]: info: CoreCommandRouter::volumioRandom Aug 25 14:04:08 volumio volumio[909]: info: CoreCommandRouter::writePlayerControls Aug 25 14:04:08 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:08 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:04:08 volumio volumio[909]: info: CoreStateMachine::setRandom true Aug 25 14:04:08 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:08 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:08 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:04:08 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:08 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:08 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:08 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::volumioPlay Aug 25 14:04:18 volumio volumio[909]: UNSET VOLATILE Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::play index 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:04:18 volumio volumio[909]: UNSET VOLATILE Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::clearAddPlayTracks USB/My Passport/Musik/Pause/Pause.mp3 Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand clear Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: Aug 25 14:04:18 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:04:18 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Pause/Pause.mp3" Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: Aug 25 14:04:18 volumio volumio[909]: info: Aug 25 14:04:18 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:04:18 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:04:18 volumio volumio[909]: info: ------------------------------ 5ms Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand play Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: ------------------------------ 4ms Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: Aug 25 14:04:18 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: Aug 25 14:04:18 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":5,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Pause","artist":"Pause","album":null,"uri":"USB/My Passport/Musik/Pause/Pause.mp3","trackType":"mp3"} Aug 25 14:04:18 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:18 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:18 volumio volumio[909]: info: ------------------------------ 34ms Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":5,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Pause","artist":"Pause","album":null,"uri":"USB/My Passport/Musik/Pause/Pause.mp3","trackType":"mp3"} Aug 25 14:04:18 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:04:18 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:18 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:18 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:18 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:18 volumio volumio[909]: info: ------------------------------ 75ms Aug 25 14:04:18 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:04:18 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:04:18 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:04:18 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:18 volumio volumio[909]: info: Prefetching next song Aug 25 14:04:18 volumio volumio[909]: info: DOING PREFETCH IN MPD Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3" Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ControllerMpd::sendMpdCommand consume 1 Aug 25 14:04:18 volumio volumio[909]: info: sending command... Aug 25 14:04:18 volumio volumio[909]: info: Aug 25 14:04:18 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:04:18 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:04:18 volumio volumio[909]: info: parsing response... Aug 25 14:04:18 volumio volumio[909]: info: ------------------------------ 2ms Aug 25 14:04:22 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:04:22 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: Aug 25 14:04:23 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:04:23 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:04:23 volumio volumio[909]: info: Aug 25 14:04:23 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:04:23 volumio volumio[909]: info: sending command... Aug 25 14:04:23 volumio volumio[909]: info: ------------------------------ 3ms Aug 25 14:04:23 volumio volumio[909]: info: Aug 25 14:04:23 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:04:23 volumio volumio[909]: info: parsing response... Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:04:23 volumio volumio[909]: info: sending command... Aug 25 14:04:23 volumio volumio[909]: info: sending command... Aug 25 14:04:23 volumio volumio[909]: info: parsing response... Aug 25 14:04:23 volumio volumio[909]: info: parsing response... Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:04:23 volumio volumio[909]: info: sending command... Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:04:23 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Jolene (The Backyard Sessions)","artist":"Miley Cyrus","album":null,"uri":"USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","trackType":"mp3"} Aug 25 14:04:23 volumio volumio[909]: info: CURRENT POSITION 2 Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:04:23 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:23 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:23 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:23 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:23 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:23 volumio volumio[909]: info: ------------------------------ 556ms Aug 25 14:04:23 volumio volumio[909]: info: parsing response... Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:04:23 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:04:23 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":180,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Jolene (The Backyard Sessions)","artist":"Miley Cyrus","album":null,"uri":"USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","trackType":"mp3"} Aug 25 14:04:23 volumio volumio[909]: info: CURRENT POSITION 2 Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:04:23 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:23 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:23 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:04:23 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:04:23 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:04:23 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:04:23 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:04:23 volumio volumio[909]: info: ------------------------------ 99ms Aug 25 14:04:23 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","favourite":false} Aug 25 14:04:23 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","favourite":false} Aug 25 14:04:23 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","favourite":false} Aug 25 14:04:23 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","favourite":false} Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::volumioNext Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::next Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:10 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:10 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::serviceStop Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::serviceStop Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::stop Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3","favourite":false} Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::clearAddPlayTracks USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3 Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:10 volumio volumio[909]: info: Aug 25 14:05:10 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand clear Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: Aug 25 14:05:10 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:10 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3" Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: Aug 25 14:05:10 volumio volumio[909]: info: Aug 25 14:05:10 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:10 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:10 volumio volumio[909]: info: ------------------------------ 6ms Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand play Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::pushError Aug 25 14:05:10 volumio volumio[909]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Aug 25 14:05:10 volumio volumio[909]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Aug 25 14:05:10 volumio volumio[909]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 25 14:05:10 volumio volumio[909]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 25 14:05:10 volumio volumio[909]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: ------------------------------ 15ms Aug 25 14:05:10 volumio volumio[909]: info: ------------------------------ 6ms Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: Aug 25 14:05:10 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: Aug 25 14:05:10 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: sending command... Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: parsing response... Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":190,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Hero","artist":"Family Of The Year","album":"Loma Vista","uri":"USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","trackType":"mp3"} Aug 25 14:05:10 volumio volumio[909]: info: CURRENT POSITION 1 Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:10 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:10 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":190,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Hero","artist":"Family Of The Year","album":"Loma Vista","uri":"USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","trackType":"mp3"} Aug 25 14:05:10 volumio volumio[909]: info: CURRENT POSITION 1 Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:10 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:10 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:10 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:10 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:10 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:10 volumio volumio[909]: info: ------------------------------ 90ms Aug 25 14:05:10 volumio volumio[909]: info: ------------------------------ 80ms Aug 25 14:05:10 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:10 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:10 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:13 volumio volumio[909]: info: CoreCommandRouter::volumioNext Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::next Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:13 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:13 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:13 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:13 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:13 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:13 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:13 volumio volumio[909]: info: CoreStateMachine::serviceStop Aug 25 14:05:13 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:13 volumio volumio[909]: info: CoreCommandRouter::serviceStop Aug 25 14:05:13 volumio volumio[909]: info: ControllerMpd::stop Aug 25 14:05:13 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:13 volumio volumio[909]: info: sending command... Aug 25 14:05:13 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:14 volumio volumio[909]: info: Aug 25 14:05:14 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:14 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:14 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:14 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:14 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:14 volumio volumio[909]: info: [1724594714030] ControllerWebradio::clearAddPlayTrack Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:14 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand clear Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: Aug 25 14:05:14 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:14 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand load "https://liveradio.swr.de/sw282p3/swr1bw/play.mp3" Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:14 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:14 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:14 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:14 volumio volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: ------------------------------ 17ms Aug 25 14:05:14 volumio volumio[909]: info: Aug 25 14:05:14 volumio volumio[909]: info: ------------------------------ 6ms Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "https://liveradio.swr.de/sw282p3/swr1bw/play.mp3" Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: Aug 25 14:05:14 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:14 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:14 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 25 14:05:14 volumio volumio[909]: info: ControllerMpd::sendMpdCommand play Aug 25 14:05:14 volumio volumio[909]: info: sending command... Aug 25 14:05:14 volumio volumio[909]: info: ------------------------------ 3ms Aug 25 14:05:14 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: Aug 25 14:05:15 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: Aug 25 14:05:15 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: Aug 25 14:05:15 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:15 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:15 volumio volumio[909]: info: Aug 25 14:05:15 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:15 volumio volumio[909]: info: Aug 25 14:05:15 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:15 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:15 volumio volumio[909]: info: Aug 25 14:05:15 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:15 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:15 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"SWR1 Baden-Württemberg mit Laura Zacharias","artist":"SWR1BW MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","trackType":"mp3"} Aug 25 14:05:15 volumio volumio[909]: info: CURRENT POSITION 0 Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: ------------------------------ 40ms Aug 25 14:05:15 volumio volumio[909]: info: ------------------------------ 33ms Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: ------------------------------ 36ms Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: sending command... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:15 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:15 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":0,"samplerate":"48 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"SWR1 Baden-Württemberg mit Laura Zacharias","artist":"SWR1BW MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","trackType":"mp3"} Aug 25 14:05:15 volumio volumio[909]: info: CURRENT POSITION 0 Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:15 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: ------------------------------ 86ms Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: parsing response... Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:15 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:15 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":0,"samplerate":"48 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"SWR1 Baden-Württemberg mit Laura Zacharias","artist":"SWR1BW MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","trackType":"mp3"} Aug 25 14:05:15 volumio volumio[909]: info: CURRENT POSITION 0 Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:15 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:15 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:15 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":0,"samplerate":"48 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"SWR1 Baden-Württemberg mit Laura Zacharias","artist":"SWR1BW MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","trackType":"mp3"} Aug 25 14:05:15 volumio volumio[909]: info: CURRENT POSITION 0 Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:15 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:15 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:15 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:15 volumio volumio[909]: info: ------------------------------ 164ms Aug 25 14:05:15 volumio volumio[909]: info: ------------------------------ 165ms Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:15 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPlay Aug 25 14:05:19 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::play index 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:19 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:19 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::serviceStop Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::serviceStop Aug 25 14:05:19 volumio volumio[909]: info: [1724594719507] ControllerWebradio::stop Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: Pushing Favourites {"service":"webradio","uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::clearAddPlayTracks USB/My Passport/Musik/Pause/Pause.mp3 Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand clear Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:19 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Pause/Pause.mp3" Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: 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":"SWR1 Baden-Württemberg mit Laura Zacharias","artist":"SWR1BW MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr1bw/play.mp3","trackType":"mp3"} Aug 25 14:05:19 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:19 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:19 volumio volumio[909]: info: No code Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:19 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: ------------------------------ 56ms Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:19 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:19 volumio volumio[909]: info: ------------------------------ 48ms Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand play Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: ------------------------------ 12ms Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Pause","artist":"Pause","album":null,"uri":"USB/My Passport/Musik/Pause/Pause.mp3","trackType":"mp3"} Aug 25 14:05:19 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:19 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:19 volumio volumio[909]: info: ------------------------------ 41ms Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":5,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Pause","artist":"Pause","album":null,"uri":"USB/My Passport/Musik/Pause/Pause.mp3","trackType":"mp3"} Aug 25 14:05:19 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:19 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:19 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:19 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:19 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:19 volumio volumio[909]: info: ------------------------------ 78ms Aug 25 14:05:19 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:19 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:19 volumio volumio[909]: info: Prefetching next song Aug 25 14:05:19 volumio volumio[909]: info: DOING PREFETCH IN MPD Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3" Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: Aug 25 14:05:19 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:19 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:19 volumio volumio[909]: info: ControllerMpd::sendMpdCommand consume 1 Aug 25 14:05:19 volumio volumio[909]: info: sending command... Aug 25 14:05:19 volumio volumio[909]: info: ------------------------------ 3ms Aug 25 14:05:19 volumio volumio[909]: info: parsing response... Aug 25 14:05:23 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:23 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: Aug 25 14:05:24 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:24 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:24 volumio volumio[909]: info: Aug 25 14:05:24 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:24 volumio volumio[909]: info: sending command... Aug 25 14:05:24 volumio volumio[909]: info: Aug 25 14:05:24 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:24 volumio volumio[909]: info: ------------------------------ 494ms Aug 25 14:05:24 volumio volumio[909]: info: parsing response... Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:24 volumio volumio[909]: info: sending command... Aug 25 14:05:24 volumio volumio[909]: info: sending command... Aug 25 14:05:24 volumio volumio[909]: info: parsing response... Aug 25 14:05:24 volumio volumio[909]: info: parsing response... Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:24 volumio volumio[909]: info: sending command... Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:24 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Rebel Yell","artist":"Billy Idol","album":"Rebel Yell","uri":"USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","trackType":"mp3"} Aug 25 14:05:24 volumio volumio[909]: info: CURRENT POSITION 3 Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:24 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:24 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:24 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:24 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:24 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:24 volumio volumio[909]: info: ------------------------------ 539ms Aug 25 14:05:24 volumio volumio[909]: info: parsing response... Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:24 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:24 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":289,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Rebel Yell","artist":"Billy Idol","album":"Rebel Yell","uri":"USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","trackType":"mp3"} Aug 25 14:05:24 volumio volumio[909]: info: CURRENT POSITION 3 Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:24 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:24 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:24 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:24 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:24 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:24 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:24 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:24 volumio volumio[909]: info: ------------------------------ 89ms Aug 25 14:05:24 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","favourite":false} Aug 25 14:05:24 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","favourite":false} Aug 25 14:05:24 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","favourite":false} Aug 25 14:05:24 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","favourite":false} Aug 25 14:05:28 volumio volumio[909]: info: CoreCommandRouter::volumioNext Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::next Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:28 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:28 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:28 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:28 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:28 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:28 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::serviceStop Aug 25 14:05:28 volumio volumio[909]: info: CorePlayQueue::getTrack 3 Aug 25 14:05:28 volumio volumio[909]: info: CoreCommandRouter::serviceStop Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::stop Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:28 volumio volumio[909]: info: sending command... Aug 25 14:05:28 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","favourite":false} Aug 25 14:05:28 volumio volumio[909]: info: parsing response... Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:28 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:05:28 volumio volumio[909]: info: Aug 25 14:05:28 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:28 volumio volumio[909]: info: sending command... Aug 25 14:05:28 volumio volumio[909]: info: parsing response... Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:28 volumio volumio[909]: info: sending command... Aug 25 14:05:28 volumio volumio[909]: info: parsing response... Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:28 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:28 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:28 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:28 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Aug 25 14:05:28 volumio volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 25 14:05:28 volumio volumio[909]: info: ------------------------------ 6ms Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::volumioNext Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::next Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:32 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::clearAddPlayTracks USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3 Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand clear Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: Aug 25 14:05:32 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:32 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3" Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: Aug 25 14:05:32 volumio volumio[909]: info: Aug 25 14:05:32 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:32 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:32 volumio volumio[909]: info: ------------------------------ 16ms Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand play Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: ------------------------------ 6ms Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: Aug 25 14:05:32 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: Aug 25 14:05:32 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:32 volumio volumio[909]: info: sending command... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":190,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Hero","artist":"Family Of The Year","album":"Loma Vista","uri":"USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","trackType":"mp3"} Aug 25 14:05:32 volumio volumio[909]: info: CURRENT POSITION 1 Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:32 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:32 volumio volumio[909]: info: ------------------------------ 61ms Aug 25 14:05:32 volumio volumio[909]: info: parsing response... Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:32 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":190,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Hero","artist":"Family Of The Year","album":"Loma Vista","uri":"USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","trackType":"mp3"} Aug 25 14:05:32 volumio volumio[909]: info: CURRENT POSITION 1 Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:32 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:32 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:32 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:32 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:32 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:32 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:32 volumio volumio[909]: info: ------------------------------ 105ms Aug 25 14:05:32 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:32 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:32 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:34 volumio volumio[909]: info: CoreCommandRouter::volumioNext Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::next Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::stop Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:34 volumio volumio[909]: UNSET VOLATILE Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::stPlaybackTimer Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:34 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:34 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:34 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::serviceStop Aug 25 14:05:34 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:34 volumio volumio[909]: info: CoreCommandRouter::serviceStop Aug 25 14:05:34 volumio volumio[909]: info: ControllerMpd::stop Aug 25 14:05:34 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:34 volumio volumio[909]: info: sending command... Aug 25 14:05:34 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Family Of The Year/Loma Vista/05 - Hero.mp3","favourite":false} Aug 25 14:05:34 volumio volumio[909]: info: parsing response... Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:34 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:34 volumio volumio[909]: info: CorePlayQueue::getTrack 2 Aug 25 14:05:34 volumio volumio[909]: info: ControllerMpd::clearAddPlayTracks USB/My Passport/Musik/Miley Cyrus/The Backyard Sessions/Miley Cyrus - The Backyard Sessions - Jolene.mp3 Aug 25 14:05:34 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:34 volumio volumio[909]: info: sending command... Aug 25 14:05:34 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:34 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:34 volumio volumio[909]: info: Aug 25 14:05:34 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:34 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:34 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:34 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio systemd-journal[170]: Suppressed 263 messages from /system.slice/volumio.service Aug 25 14:05:40 volumio volumio[909]: info: Aug 25 14:05:40 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::play index undefined Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::clearAddPlayTracks USB/My Passport/Musik/Pause/Pause.mp3 Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand stop Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::updateTrackBlock Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrackBlock Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand clear Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: Aug 25 14:05:40 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:40 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand add "USB/My Passport/Musik/Pause/Pause.mp3" Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: 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":"Rebel Yell","artist":"Billy Idol","album":"Rebel Yell","uri":"USB/My Passport/Musik/Billy Idol/Rebel Yell/01 - Rebel Yell [Explicit].mp3","trackType":"mp3"} Aug 25 14:05:40 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:40 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:40 volumio volumio[909]: info: No code Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:40 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: ------------------------------ 55ms Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:05:40 volumio volumio[909]: info: Aug 25 14:05:40 volumio volumio[909]: info: Aug 25 14:05:40 volumio volumio[909]: ---------------------------- MPD announces system playlist update Aug 25 14:05:40 volumio volumio[909]: info: Ignoring MPD Status Update Aug 25 14:05:40 volumio volumio[909]: info: ------------------------------ 48ms Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand play Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: ------------------------------ 8ms Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:40 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:40 volumio volumio[909]: info: Aug 25 14:05:40 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: Aug 25 14:05:40 volumio volumio[909]: ---------------------------- MPD announces state update: player Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::getState Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand status Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::parseState Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:40 volumio volumio[909]: info: sending command... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":5,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Pause","artist":"Pause","album":null,"uri":"USB/My Passport/Musik/Pause/Pause.mp3","trackType":"mp3"} Aug 25 14:05:40 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:40 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:40 volumio volumio[909]: info: ------------------------------ 31ms Aug 25 14:05:40 volumio volumio[909]: info: parsing response... Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::parseTrackInfo Aug 25 14:05:40 volumio volumio[909]: info: ControllerMpd::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::servicePushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":5,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Pause","artist":"Pause","album":null,"uri":"USB/My Passport/Musik/Pause/Pause.mp3","trackType":"mp3"} Aug 25 14:05:40 volumio volumio[909]: info: CURRENT POSITION 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState stateService play Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus play Aug 25 14:05:40 volumio volumio[909]: info: Received an update from plugin. extracting info from payload Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:40 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::pushState Aug 25 14:05:40 volumio volumio[909]: info: CoreStateMachine::getState Aug 25 14:05:40 volumio volumio[909]: info: CorePlayQueue::getTrack 4 Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Aug 25 14:05:40 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 14:05:40 volumio volumio[909]: info: interfaceApi::pushState Aug 25 14:05:40 volumio volumio[909]: info: ------------------------------ 65ms Aug 25 14:05:40 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:40 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:40 volumio volumio[909]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/My Passport/Musik/Pause/Pause.mp3","favourite":false} Aug 25 14:05:44 volumio volumio[909]: info: CoreStateMachine::startPlaybackTimer Aug 25 14:05:44 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:05:44 volumio volumio[909]: info: CorePlayQueue::getTrack 5 Aug 25 14:05:44 volumio volumio[909]: info: CorePlayQueue::getTrack 1 Aug 25 14:05:44 volumio volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 14:05:44 volumio volumio[909]: TypeError: Cannot read property 'service' of undefined Aug 25 14:05:44 volumio volumio[909]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:443:97) Aug 25 14:05:44 volumio volumio[909]: at ontimeout (timers.js:482:11) Aug 25 14:05:44 volumio volumio[909]: at tryOnTimeout (timers.js:317:5) Aug 25 14:05:44 volumio volumio[909]: at Timer.listOnTimeout (timers.js:277:5) Aug 25 14:05:44 volumio volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 14:05:45 volumio sudo[26038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 14:04 Aug 25 14:05:45 volumio sudo[26038]: 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="d0d14b37ffe8e45861e4e0b9816241fbafb4eb91" VOLUMIO_FE_VERSION="81c7100e9c7342b16fc9e0ca5908a26771e08c4f" VOLUMIO_BE_VERSION="645f0e55945bdd625a662a1ee21ba0dfb11bd73e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jun 21 01:30:10 CEST 2018" VOLUMIO_VERSION="2.413" VOLUMIO_HARDWARE="pi"