-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-08-17 21:15:37 CEST. -- Aug 17 21:14:00 volumio volumio[927]: error: updateQueue error: null Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand add "http://stream-dc1.radioparadise.com/rp_192m.ogg" Aug 17 21:14:00 volumio volumio[927]: info: ------------------------------ 930ms Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:00 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand add "http://stream-dc1.radioparadise.com/rp_192m.ogg" took 2 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand play Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:00 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:00 volumio volumio[927]: info: ------------------------------ 8ms Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand play took 3 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: ------------------------------ 2ms Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand status took 6 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand status took 3 milliseconds Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand status took 8 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand status took 4 milliseconds Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:00 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:00 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rp_192m.ogg","artist":null,"album":null,"uri":"http://stream-dc1.radioparadise.com/rp_192m.ogg","trackType":"ogg"} Aug 17 21:14:00 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:00 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:00 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rp_192m.ogg","artist":null,"album":null,"uri":"http://stream-dc1.radioparadise.com/rp_192m.ogg","trackType":"ogg"} Aug 17 21:14:00 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:00 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:00 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:00 volumio volumio[927]: info: ------------------------------ 48ms Aug 17 21:14:00 volumio volumio[927]: info: ------------------------------ 59ms Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:00 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:00 volumio volumio[927]: info: Aug 17 21:14:00 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand playlistinfo took 47 milliseconds Aug 17 21:14:00 volumio volumio[927]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:00 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:00 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:00 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:00 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Moses - Grace","artist":"Radio Paradise (192k Ogg/Vorbis) - playlist: radioparadise.com","album":null,"uri":"http://stream-dc1.radioparadise.com/rp_192m.ogg","trackType":"ogg"} Aug 17 21:14:00 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:00 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:00 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:00 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:01 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Moses - Grace","artist":"Radio Paradise (192k Ogg/Vorbis) - playlist: radioparadise.com","album":null,"uri":"http://stream-dc1.radioparadise.com/rp_192m.ogg","trackType":"ogg"} Aug 17 21:14:01 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:01 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: ------------------------------ 144ms Aug 17 21:14:01 volumio volumio[927]: info: ------------------------------ 138ms Aug 17 21:14:01 volumio volumio[927]: info: Aug 17 21:14:01 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:01 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:01 volumio volumio[927]: info: Aug 17 21:14:01 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:01 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: info: ------------------------------ 133ms Aug 17 21:14:01 volumio volumio[927]: info: sendMpdCommand status took 130 milliseconds Aug 17 21:14:01 volumio volumio[927]: info: ------------------------------ 45ms Aug 17 21:14:01 volumio volumio[927]: info: sendMpdCommand status took 23 milliseconds Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:01 volumio volumio[927]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 17 21:14:01 volumio volumio[927]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:01 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:01 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:01 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186172,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Moses - Grace","artist":"Radio Paradise (192k Ogg/Vorbis) - playlist: radioparadise.com","album":null,"uri":"http://stream-dc1.radioparadise.com/rp_192m.ogg","trackType":"ogg"} Aug 17 21:14:01 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:01 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:01 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186297,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Moses - Grace","artist":"Radio Paradise (192k Ogg/Vorbis) - playlist: radioparadise.com","album":null,"uri":"http://stream-dc1.radioparadise.com/rp_192m.ogg","trackType":"ogg"} Aug 17 21:14:01 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:01 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:01 volumio volumio[927]: info: ------------------------------ 180ms Aug 17 21:14:01 volumio volumio[927]: info: ------------------------------ 92ms Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:01 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::ClearQueue Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::stop Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::stPlaybackTimer Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::updateTrackBlock Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrackBlock Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::serviceStop Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::serviceStop Aug 17 21:14:02 volumio volumio[927]: info: [1723922042118] ControllerWebradio::stop Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::clearPlayQueue Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::saveQueue Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushQueue Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::addQueueItems Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::addQueueItems Aug 17 21:14:02 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:02 volumio volumio[927]: info: Adding Item to queue: http://stream.radioparadise.com/flacm Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushQueue Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::saveQueue Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::updateTrackBlock Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrackBlock Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPlay Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::play index 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::stop Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::play index undefined Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::startPlaybackTimer Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: [1723922042138] ControllerWebradio::clearAddPlayTrack Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand stop took 26 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand stop took 5 milliseconds Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:02 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand status took 5 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand clear took 4 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand status took 3 milliseconds Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 18ms Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:02 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:02 volumio volumio[927]: error: updateQueue error: null Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 19ms Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:02 volumio volumio[927]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 25ms Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: error: updateQueue error: null Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 631ms Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:02 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 2 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand play Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:02 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 7ms Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand play took 5 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 4ms Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand status took 4 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand status took 3 milliseconds Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:02 volumio volumio[927]: info: Aug 17 21:14:02 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand status took 13 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand status took 4 milliseconds Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:02 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:02 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:02 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 36ms Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 46ms Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 17 21:14:02 volumio volumio[927]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:02 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:02 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:02 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:02 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:02 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:02 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 105ms Aug 17 21:14:02 volumio volumio[927]: info: ------------------------------ 94ms Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:02 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:03 volumio volumio[927]: info: Aug 17 21:14:03 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:03 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:03 volumio volumio[927]: info: Aug 17 21:14:03 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:03 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:03 volumio volumio[927]: info: Aug 17 21:14:03 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:03 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:03 volumio volumio[927]: info: Aug 17 21:14:03 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:03 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:03 volumio volumio[927]: info: ------------------------------ 10ms Aug 17 21:14:03 volumio volumio[927]: info: sendMpdCommand status took 7 milliseconds Aug 17 21:14:03 volumio volumio[927]: info: ------------------------------ 6ms Aug 17 21:14:03 volumio volumio[927]: info: sendMpdCommand status took 4 milliseconds Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:03 volumio volumio[927]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 17 21:14:03 volumio volumio[927]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:03 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:03 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:03 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:03 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:03 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:03 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:03 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:03 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:03 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:03 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:03 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:03 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:03 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:03 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:03 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:03 volumio volumio[927]: info: ------------------------------ 59ms Aug 17 21:14:03 volumio volumio[927]: info: ------------------------------ 56ms Aug 17 21:14:03 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:03 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:03 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:03 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:07 volumio volumio[927]: info: Aug 17 21:14:07 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:07 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:07 volumio volumio[927]: info: Aug 17 21:14:07 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:07 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:07 volumio volumio[927]: info: Aug 17 21:14:07 volumio volumio[927]: ---------------------------- MPD announces system playlist update Aug 17 21:14:07 volumio volumio[927]: info: Ignoring MPD Status Update Aug 17 21:14:07 volumio volumio[927]: info: Aug 17 21:14:07 volumio volumio[927]: ---------------------------- MPD announces state update: player Aug 17 21:14:07 volumio volumio[927]: info: ControllerMpd::getState Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Aug 17 21:14:07 volumio volumio[927]: info: ------------------------------ 9ms Aug 17 21:14:07 volumio volumio[927]: info: sendMpdCommand status took 8 milliseconds Aug 17 21:14:07 volumio volumio[927]: info: ------------------------------ 7ms Aug 17 21:14:07 volumio volumio[927]: info: sendMpdCommand status took 6 milliseconds Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::parseState Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 21:14:07 volumio volumio[927]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 17 21:14:07 volumio volumio[927]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:07 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Aug 17 21:14:07 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:07 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:07 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4965,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Moses - Grace","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:07 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:07 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:07 volumio volumio[927]: info: ControllerMpd::pushState Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::servicePushState Aug 17 21:14:07 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Aug 17 21:14:07 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4965,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bob Moses - Grace","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Aug 17 21:14:07 volumio volumio[927]: verbose: CURRENT POSITION 0 Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Aug 17 21:14:07 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:07 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:07 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:07 volumio volumio[927]: info: ------------------------------ 52ms Aug 17 21:14:07 volumio volumio[927]: info: ------------------------------ 51ms Aug 17 21:14:07 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:07 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:07 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:07 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 17 21:14:11 volumio volumio[927]: info: VolumeController::SetAlsaVolume56 Aug 17 21:14:11 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:11 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:11 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:11 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Aug 17 21:14:11 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 45 Aug 17 21:14:11 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 56 Aug 17 21:14:11 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 17 21:14:11 volumio volumio[927]: info: Setting Spotify Volume from Volumio: 56 Aug 17 21:14:12 volumio volumio[927]: SPOTIFY: SETTING SPOTIFY VOLUME 56 Aug 17 21:14:12 volumio volumio[927]: info: Sending Spotify command with payload to local API: /player/volume Aug 17 21:14:14 volumio volumio[927]: info: VolumeController::SetAlsaVolume+ Aug 17 21:14:14 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:14 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:14 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Aug 17 21:14:14 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 56 Aug 17 21:14:14 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 57 Aug 17 21:14:14 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 17 21:14:14 volumio volumio[927]: info: VolumeController::SetAlsaVolume+ Aug 17 21:14:14 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:14 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:14 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 56 Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 58 Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 17 21:14:15 volumio volumio[927]: info: Setting Spotify Volume from Volumio: 58 Aug 17 21:14:15 volumio volumio[927]: info: VolumeController::SetAlsaVolume+ Aug 17 21:14:15 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:15 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:15 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 58 Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 59 Aug 17 21:14:15 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 17 21:14:16 volumio volumio[927]: SPOTIFY: SETTING SPOTIFY VOLUME 58 Aug 17 21:14:16 volumio volumio[927]: info: Sending Spotify command with payload to local API: /player/volume Aug 17 21:14:17 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:17 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Aug 17 21:14:17 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:17 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 58 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 57 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 17 21:14:17 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:17 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 58 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 56 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 17 21:14:17 volumio volumio[927]: info: Setting Spotify Volume from Volumio: 56 Aug 17 21:14:17 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:17 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:17 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 56 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 55 Aug 17 21:14:17 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 17 21:14:18 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:18 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 56 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 54 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 17 21:14:18 volumio volumio[927]: info: Setting Spotify Volume from Volumio: 54 Aug 17 21:14:18 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:18 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 54 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 53 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 17 21:14:18 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:18 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 54 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 52 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 17 21:14:18 volumio volumio[927]: info: Setting Spotify Volume from Volumio: 52 Aug 17 21:14:18 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:18 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:18 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 52 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 51 Aug 17 21:14:18 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 17 21:14:19 volumio volumio[927]: info: VolumeController::SetAlsaVolume- Aug 17 21:14:19 volumio volumio[927]: info: CoreStateMachine::pushState Aug 17 21:14:19 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 21:14:19 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Aug 17 21:14:19 volumio volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Aug 17 21:14:19 volumio volumio[927]: SPOTIFY: SPOTIFY VOLUME 52 Aug 17 21:14:19 volumio volumio[927]: SPOTIFY: VOLUMIO VOLUME 50 Aug 17 21:14:19 volumio volumio[927]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 17 21:14:19 volumio volumio[927]: info: Setting Spotify Volume from Volumio: 50 Aug 17 21:14:20 volumio volumio[927]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Aug 17 21:14:20 volumio volumio[927]: info: Sending Spotify command with payload to local API: /player/volume Aug 17 21:14:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 17 21:14:25 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Aug 17 21:14:28 volumio volumio[927]: Error Retrieving last played file: Error: /data/laststates.json: ENOENT: no such file or directory, open '/data/laststates.json' Aug 17 21:14:28 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:30 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 17 21:14:30 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:34 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 21:14:34 volumio volumio[927]: info: CURURI: playlists Aug 17 21:14:34 volumio volumio[927]: info: Listing playlists Aug 17 21:14:34 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:36 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 21:14:36 volumio volumio[927]: info: CURURI: music-library Aug 17 21:14:36 volumio volumio[927]: error: Failed LSINFO: null Aug 17 21:14:36 volumio volumio[927]: info: Preload queue cleared Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 17 21:14:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 17 21:15:00 volumio systemd[1]: Starting Cleanup of Temporary Directories... Aug 17 21:15:00 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Aug 17 21:15:00 volumio systemd[1]: Started Cleanup of Temporary Directories. Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:15:08 volumio volumio[927]: info: Retrieving Cloud Streaming UI Aug 17 21:15:08 volumio volumio[927]: info: Getting Tidal Cloud Configuration Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:15:08 volumio volumio[927]: info: Getting Qobuz Cloud Configuration Aug 17 21:15:08 volumio volumio[927]: info: Asking plugin for UI Config Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:15:08 volumio volumio[927]: info: Getting Spotify Cloud Configuration Aug 17 21:15:08 volumio volumio[927]: info: Asking plugin for UI Config Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:15:08 volumio volumio[927]: info: Saving Spotify Acccount Aug 17 21:15:08 volumio volumio[927]: info: Got Tidal Cloud Configuration Aug 17 21:15:08 volumio volumio[927]: info: Got it Aug 17 21:15:08 volumio volumio[927]: info: Got it Aug 17 21:15:08 volumio volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 17 21:15:08 volumio volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 17 21:15:12 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:16 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:20 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:24 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:25 volumio volumio[927]: info: Disabling MyMusic plugin upnp_browser Aug 17 21:15:25 volumio volumio[927]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Aug 17 21:15:25 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 21:15:25 volumio volumio[927]: info: Disabling plugin upnp_browser Aug 17 21:15:25 volumio volumio[927]: info: Done. Aug 17 21:15:27 volumio wpa_supplicant[2329]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="tony en anita 2" Aug 17 21:15:27 volumio wpa_supplicant[2329]: wlan0: Trying to associate with SSID 'tony en anita 2' Aug 17 21:15:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:29 volumio volumio[927]: info: Disabling MyMusic plugin airplay_emulation Aug 17 21:15:29 volumio volumio[927]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Aug 17 21:15:29 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 21:15:29 volumio volumio[927]: info: Disabling plugin airplay_emulation Aug 17 21:15:29 volumio volumio[927]: info: Done. Aug 17 21:15:29 volumio sudo[3894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Aug 17 21:15:29 volumio sudo[3894]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:15:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 21:15:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 21:15:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 21:15:29 volumio sudo[3894]: pam_unix(sudo:session): session closed for user root Aug 17 21:15:29 volumio volumio[927]: info: Shairport-Sync Stopped Aug 17 21:15:31 volumio volumio[927]: info: Disabling MyMusic plugin upnp Aug 17 21:15:31 volumio sudo[3898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 17 21:15:31 volumio sudo[3898]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 21:15:31 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 17 21:15:31 volumio volumio[927]: error: Upnp client error: Error: This socket has been ended by the other party Aug 17 21:15:32 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:34 volumio wpa_supplicant[2329]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=d8:33:b7:c0:3c:e1 status_code=16 Aug 17 21:15:34 volumio wpa_supplicant[2329]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="tony en anita 2" auth_failures=9 duration=102 reason=CONN_FAILED Aug 17 21:15:36 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 17 21:15:36 volumio volumio[927]: info: Enabling MyMusic plugin upnp Aug 17 21:15:36 volumio volumio[927]: info: Enabling plugin upnp Aug 17 21:15:36 volumio volumio[927]: info: Loading plugin "upnp"... Aug 17 21:15:36 volumio volumio[927]: info: [1723922136673] Starting Upmpd Daemon Aug 17 21:15:36 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 21:15:36 volumio volumio[927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 21:15:36 volumio volumio[927]: Error: listen EADDRINUSE: address already in use :::6599 Aug 17 21:15:36 volumio volumio[927]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Aug 17 21:15:36 volumio volumio[927]: at listenInCluster (net.js:1366:12) Aug 17 21:15:36 volumio volumio[927]: at Server.listen (net.js:1452:7) Aug 17 21:15:36 volumio volumio[927]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 17 21:15:36 volumio volumio[927]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 17 21:15:36 volumio volumio[927]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 17 21:15:36 volumio volumio[927]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 17 21:15:36 volumio volumio[927]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 17 21:15:36 volumio volumio[927]: code: 'EADDRINUSE', Aug 17 21:15:36 volumio volumio[927]: errno: -98, Aug 17 21:15:36 volumio volumio[927]: syscall: 'listen', Aug 17 21:15:36 volumio volumio[927]: address: '::', Aug 17 21:15:36 volumio volumio[927]: port: 6599 Aug 17 21:15:36 volumio volumio[927]: } Aug 17 21:15:36 volumio volumio[927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 21:15:37 volumio sudo[3909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 21:14 Aug 17 21:15:37 volumio sudo[3909]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"