-- Logs begin at Sun 2024-04-14 09:51:51 CEST, end at Sun 2024-04-14 09:58:14 CEST. -- Apr 14 09:57:02 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:57:09 volumio volumio[681]: info: CoreCommandRouter::volumioGetQueue Apr 14 09:57:09 volumio volumio[681]: info: CoreStateMachine::getQueue Apr 14 09:57:09 volumio volumio[681]: info: CorePlayQueue::getQueue Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:57:12 volumio volumio[681]: info: Listing playlists Apr 14 09:57:12 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::ClearQueue Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::stPlaybackTimer Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:57:12 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:12 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::serviceStop Apr 14 09:57:12 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::serviceStop Apr 14 09:57:12 volumio volumio[681]: info: ControllerMpd::stop Apr 14 09:57:12 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:57:12 volumio volumio[681]: info: CorePlayQueue::clearPlayQueue Apr 14 09:57:12 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:57:12 volumio volumio[681]: info: CoreStateMachine::addQueueItems Apr 14 09:57:12 volumio volumio[681]: info: CorePlayQueue::addQueueItems Apr 14 09:57:12 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:12 volumio volumio[681]: info: Adding Item to queue: webrns/0 Apr 14 09:57:12 volumio volumio[681]: info: Exploding uri webrns/0 in service rad357pl Apr 14 09:57:12 volumio volumio[681]: info: [1713081432851] [rad357pl] explodeUri: webrns/0 Apr 14 09:57:12 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::volumioPlay Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::play index 0 Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::play index undefined Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::startPlaybackTimer Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:57:13 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand stop took 270 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand stop took 54 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received nowyswiat Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:13 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand status took 135 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand clear took 92 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand status took 55 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand status took 52 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand status took 43 milliseconds Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:13 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:13 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 167ms Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 162ms Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 160ms Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:13 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:13 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:13 volumio volumio[681]: error: updateQueue error: null Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 203ms Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand playlistinfo took 189 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 189 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 75ms Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 73ms Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 72ms Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:13 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand play Apr 14 09:57:13 volumio volumio[681]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 354ms Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 39ms Apr 14 09:57:13 volumio volumio[681]: info: sendMpdCommand play took 30 milliseconds Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 27ms Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 22ms Apr 14 09:57:13 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 09:57:13 volumio volumio[681]: info: Apr 14 09:57:13 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:13 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:13 volumio volumio[681]: info: ------------------------------ 25ms Apr 14 09:57:13 volumio volumio[681]: info: camilladsp spawned new process with pid 2250, instance 1, run: true Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:14 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:14 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:14 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand status took 27 milliseconds Apr 14 09:57:14 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:14 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:14 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:14 volumio volumio[681]: info: Apr 14 09:57:14 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:14 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:14 volumio volumio[681]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 09:57:14 volumio volumio[681]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 09:57:14 volumio volumio[681]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand status took 52 milliseconds Apr 14 09:57:14 volumio volumio[681]: info: ------------------------------ 66ms Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand status took 52 milliseconds Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand playlistinfo took 52 milliseconds Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand status took 41 milliseconds Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand status took 40 milliseconds Apr 14 09:57:14 volumio volumio[681]: info: sendMpdCommand status took 38 milliseconds Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: 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":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 105ms Apr 14 09:57:15 volumio volumio[681]: info: Apr 14 09:57:15 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:15 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:15 volumio volumio[681]: info: Apr 14 09:57:15 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:15 volumio volumio[681]: info: Apr 14 09:57:15 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:15 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:15 volumio volumio[681]: info: Apr 14 09:57:15 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:15 volumio volumio[681]: info: Apr 14 09:57:15 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:15 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:15 volumio volumio[681]: info: Apr 14 09:57:15 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 26ms Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand status took 15 milliseconds Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 238ms Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 237ms Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 222ms Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 213ms Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 211ms Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 165ms Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand status took 163 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 163ms Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand status took 162 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 148 milliseconds Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 206ms Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 110 milliseconds Apr 14 09:57:15 volumio volumio[681]: info: sendMpdCommand playlistinfo took 112 milliseconds Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:15 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:15 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:15 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1605,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:15 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:15 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:15 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 377ms Apr 14 09:57:15 volumio volumio[681]: info: ------------------------------ 385ms Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:15 volumio volumio[681]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 14 09:57:22 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:57:23 volumio nmbd[640]: [2024/04/14 09:57:23.889643, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 14 09:57:23 volumio nmbd[640]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.7 for name WORKGROUP<1d>. Apr 14 09:57:23 volumio nmbd[640]: This response was from IP 192.168.1.35, reporting an IP address of 192.168.1.35. Apr 14 09:57:24 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: nowyswiat , handleBrowseUri Apr 14 09:57:24 volumio volumio[681]: info: [1713081444311] [RadioNowySwiat] handleBrowseUri curUri: nowyswiat Apr 14 09:57:24 volumio volumio[681]: info: [1713081444313] [RadioNowySwiat] getRadioContent url: nowyswiat Apr 14 09:57:24 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:25 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::ClearQueue Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::stPlaybackTimer Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::serviceStop Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::serviceStop Apr 14 09:57:25 volumio volumio[681]: info: ControllerMpd::stop Apr 14 09:57:25 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::clearPlayQueue Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::addQueueItems Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::addQueueItems Apr 14 09:57:25 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:25 volumio volumio[681]: info: Adding Item to queue: webrns/0 Apr 14 09:57:25 volumio volumio[681]: info: Using cached record of: webrns/0 Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::volumioPlay Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::play index 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::play index undefined Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::startPlaybackTimer Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:57:25 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:25 volumio volumio[681]: info: Apr 14 09:57:25 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:25 volumio volumio[681]: info: sendMpdCommand stop took 160 milliseconds Apr 14 09:57:25 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:25 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:25 volumio volumio[681]: info: sendMpdCommand stop took 61 milliseconds Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: verbose: STATE SERVICE {"status":"stop"} Apr 14 09:57:25 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::syncState stateService stop Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:25 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:25 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:25 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: No code Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:26 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 152 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand clear took 115 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 50 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 48 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 47 milliseconds Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 173ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 177ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 177ms Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:26 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:26 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:26 volumio volumio[681]: error: updateQueue error: null Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 206ms Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand playlistinfo took 207 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 209 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 82ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 79ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 78ms Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand play Apr 14 09:57:26 volumio volumio[681]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 427ms Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 132ms Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand play took 61 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 54ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 38ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 31ms Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 35 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 36 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 31 milliseconds Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 09:57:26 volumio volumio[681]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 09:57:26 volumio volumio[681]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 75 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 63 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand playlistinfo took 61 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 54 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 46 milliseconds Apr 14 09:57:26 volumio volumio[681]: info: sendMpdCommand status took 44 milliseconds Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: 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":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:26 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: 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":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:26 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:26 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: 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":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:26 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:26 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:26 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 162ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 218ms Apr 14 09:57:26 volumio volumio[681]: info: ------------------------------ 210ms Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:26 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:26 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:26 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:26 volumio volumio[681]: info: Apr 14 09:57:26 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:27 volumio volumio[681]: info: Apr 14 09:57:27 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:27 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:27 volumio volumio[681]: info: Apr 14 09:57:27 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 143ms Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand status took 146 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 143 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 133 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 132 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 134 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 38ms Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand status took 37 milliseconds Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: 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":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: 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":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 609ms Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 590ms Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 576ms Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 568ms Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 562ms Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: info: camilladsp spawned new process with pid 2304, instance 1, run: true Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 540ms Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand status took 536 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 536ms Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand status took 532 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 506 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 491 milliseconds Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 789ms Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 669ms Apr 14 09:57:27 volumio volumio[681]: error: FusionDsp - WebSocket error: [object Object] Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 127 milliseconds Apr 14 09:57:27 volumio volumio[681]: info: sendMpdCommand playlistinfo took 127 milliseconds Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:27 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:27 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"132 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:27 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:27 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:27 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 833ms Apr 14 09:57:27 volumio volumio[681]: info: ------------------------------ 827ms Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:27 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:32 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:57:32 volumio volumio[681]: info: Listing playlists Apr 14 09:57:32 volumio volumio[681]: info: Listing playlists Apr 14 09:57:35 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Apr 14 09:57:35 volumio volumio[681]: info: [1713081455011] [rad357pl] handleBrowseUri curUri: rad357pl Apr 14 09:57:35 volumio volumio[681]: info: [1713081455012] [rad357pl] getRadioContent url: rad357pl Apr 14 09:57:35 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:36 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::ClearQueue Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::stPlaybackTimer Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::serviceStop Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::serviceStop Apr 14 09:57:36 volumio volumio[681]: info: ControllerMpd::stop Apr 14 09:57:36 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::clearPlayQueue Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::addQueueItems Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::addQueueItems Apr 14 09:57:36 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:36 volumio volumio[681]: info: Adding Item to queue: webrns/0 Apr 14 09:57:36 volumio volumio[681]: info: Using cached record of: webrns/0 Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::volumioPlay Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::play index 0 Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::play index undefined Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::startPlaybackTimer Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:36 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:57:36 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:36 volumio volumio[681]: info: Apr 14 09:57:36 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:36 volumio volumio[681]: info: sendMpdCommand stop took 58 milliseconds Apr 14 09:57:36 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:36 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:36 volumio volumio[681]: info: sendMpdCommand stop took 31 milliseconds Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:36 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:36 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:36 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: verbose: STATE SERVICE {"status":"stop"} Apr 14 09:57:37 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState stateService stop Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: No code Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:37 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 68 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand clear took 51 milliseconds Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: error: updateQueue error: null Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:37 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 19ms Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 15 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 10 milliseconds Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand play Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 126ms Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 44ms Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:37 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:37 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 48 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 45 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 44ms Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand play took 32 milliseconds Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 14 09:57:37 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState stateService stop Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: No code Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 14 09:57:37 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState stateService stop Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: No code Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 358ms Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 357ms Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:37 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 401ms Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 398ms Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 81ms Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 63 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 45 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 26 milliseconds Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 14 09:57:37 volumio kernel: xhci_hcd 0000:07:1b.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 1 comp_code 1 Apr 14 09:57:37 volumio kernel: xhci_hcd 0000:07:1b.0: Looking for event-dma 00000001102266f0 trb-start 00000001102266e0 trb-end 00000001102266e0 seg-start 0000000110226000 seg-end 0000000110226ff0 Apr 14 09:57:37 volumio volumio[681]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 14 09:57:37 volumio volumio[681]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:37 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:37 volumio volumio[681]: info: Apr 14 09:57:37 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 77 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 69 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand playlistinfo took 62 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand playlistinfo took 57 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 60 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 61 milliseconds Apr 14 09:57:37 volumio volumio[681]: info: sendMpdCommand status took 60 milliseconds Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:37 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: 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":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:37 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus stop Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: 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":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:37 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:37 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:37 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:37 volumio volumio[681]: 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":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:37 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:37 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:37 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:37 volumio volumio[681]: info: ------------------------------ 183ms Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 247ms Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 228ms Apr 14 09:57:38 volumio volumio[681]: info: Apr 14 09:57:38 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:38 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:38 volumio volumio[681]: info: Apr 14 09:57:38 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:38 volumio volumio[681]: info: Apr 14 09:57:38 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:38 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:38 volumio volumio[681]: info: Apr 14 09:57:38 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:38 volumio volumio[681]: info: Apr 14 09:57:38 volumio volumio[681]: ---------------------------- MPD announces system playlist update Apr 14 09:57:38 volumio volumio[681]: info: Ignoring MPD Status Update Apr 14 09:57:38 volumio volumio[681]: info: Apr 14 09:57:38 volumio volumio[681]: ---------------------------- MPD announces state update: player Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::getState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand status Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 145ms Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand status took 140 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 122 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 121 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 122 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 122 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 24ms Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand status took 20 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 19ms Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand status took 16 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 16ms Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand status took 12 milliseconds Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseState Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: 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":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 515ms Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 507ms Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 481ms Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 482ms Apr 14 09:57:38 volumio volumio[681]: info: ------------------------------ 481ms Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 617 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 616 milliseconds Apr 14 09:57:38 volumio volumio[681]: info: sendMpdCommand playlistinfo took 622 milliseconds Apr 14 09:57:38 volumio volumio[681]: verbose: ControllerMpd::parseTrackInfo Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: ControllerMpd::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::servicePushState Apr 14 09:57:38 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:57:38 volumio volumio[681]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Stereophonics - Maybe Tomorrow","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Apr 14 09:57:38 volumio volumio[681]: verbose: CURRENT POSITION 0 Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState stateService play Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::syncState currentStatus play Apr 14 09:57:38 volumio volumio[681]: info: Received an update from plugin. extracting info from payload Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:38 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:57:38 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:57:39 volumio volumio[681]: info: ------------------------------ 1173ms Apr 14 09:57:39 volumio volumio[681]: info: ------------------------------ 1047ms Apr 14 09:57:39 volumio volumio[681]: info: ------------------------------ 1042ms Apr 14 09:57:39 volumio volumio[681]: info: ------------------------------ 1039ms Apr 14 09:57:39 volumio volumio[681]: error: FusionDsp - WebSocket error: [object Object] Apr 14 09:57:39 volumio volumio[681]: info: camilladsp spawned new process with pid 2355, instance 1, run: true Apr 14 09:57:39 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:39 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:39 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:39 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:39 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:39 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:57:42 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:57:46 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 09:57:46 volumio volumio[681]: info: CURURI: music-library Apr 14 09:57:46 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:48 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 09:57:48 volumio volumio[681]: info: CURURI: music-library/NAS Apr 14 09:57:48 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:49 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 09:57:49 volumio volumio[681]: info: CURURI: music-library/NAS/muza Apr 14 09:57:49 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:49 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/radiopl.m3u Apr 14 09:57:49 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/radiopl.m3u in service mpd Apr 14 09:57:54 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:57:54 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 09:57:54 volumio volumio[681]: info: CURURI: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum Apr 14 09:57:54 volumio volumio[681]: info: Listing playlists Apr 14 09:57:54 volumio volumio[681]: info: Preload queue cleared Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/01 - Nightwish - Taikatalvi.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/02 - Nightwish - Storytime.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/03 - Nightwish - Ghost River.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/04 - Nightwish - Slow, Love, Slow.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/05 - Nightwish - I Want My Tears Back.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/06 - Nightwish - Scaretale.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/07 - Nightwish - Arabesque.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/08 - Nightwish - Turn Loose The Mermaids.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/09 - Nightwish - Rest Calm.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/10 - Nightwish - The Crow, The Owl And The Dove.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/11 - Nightwish - Last Ride Of The Day.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/12 - Nightwish - Song Of Myself.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/13 - Nightwish - Imaginaerum.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/01 - Nightwish - Taikatalvi.mp3 in service mpd Apr 14 09:57:54 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/02 - Nightwish - Storytime.mp3 in service mpd Apr 14 09:57:54 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F01%20-%20Nightwish%20-%20Taikatalvi.mp3&metadata=false Apr 14 09:57:54 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/01 - Nightwish - Taikatalvi.mp3 Apr 14 09:57:54 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F02%20-%20Nightwish%20-%20Storytime.mp3&metadata=false Apr 14 09:57:54 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/02 - Nightwish - Storytime.mp3 Apr 14 09:57:54 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/03 - Nightwish - Ghost River.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/04 - Nightwish - Slow, Love, Slow.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/05 - Nightwish - I Want My Tears Back.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/06 - Nightwish - Scaretale.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/07 - Nightwish - Arabesque.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F03%20-%20Nightwish%20-%20Ghost%20River.mp3&metadata=false Apr 14 09:57:55 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/03 - Nightwish - Ghost River.mp3 Apr 14 09:57:55 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F04%20-%20Nightwish%20-%20Slow%2C%20Love%2C%20Slow.mp3&metadata=false Apr 14 09:57:55 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/04 - Nightwish - Slow, Love, Slow.mp3 Apr 14 09:57:55 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F05%20-%20Nightwish%20-%20I%20Want%20My%20Tears%20Back.mp3&metadata=false Apr 14 09:57:55 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/05 - Nightwish - I Want My Tears Back.mp3 Apr 14 09:57:55 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F06%20-%20Nightwish%20-%20Scaretale.mp3&metadata=false Apr 14 09:57:55 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/06 - Nightwish - Scaretale.mp3 Apr 14 09:57:55 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F07%20-%20Nightwish%20-%20Arabesque.mp3&metadata=false Apr 14 09:57:55 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/07 - Nightwish - Arabesque.mp3 Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/08 - Nightwish - Turn Loose The Mermaids.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/09 - Nightwish - Rest Calm.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/10 - Nightwish - The Crow, The Owl And The Dove.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/11 - Nightwish - Last Ride Of The Day.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/12 - Nightwish - Song Of Myself.mp3 in service mpd Apr 14 09:57:55 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Nightwish - 2011 - Imaginaerum/13 - Nightwish - Imaginaerum.mp3 in service mpd Apr 14 09:57:56 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F08%20-%20Nightwish%20-%20Turn%20Loose%20The%20Mermaids.mp3&metadata=false Apr 14 09:57:56 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/08 - Nightwish - Turn Loose The Mermaids.mp3 Apr 14 09:57:56 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F09%20-%20Nightwish%20-%20Rest%20Calm.mp3&metadata=false Apr 14 09:57:56 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/09 - Nightwish - Rest Calm.mp3 Apr 14 09:57:56 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F10%20-%20Nightwish%20-%20The%20Crow%2C%20The%20Owl%20And%20The%20Dove.mp3&metadata=false Apr 14 09:57:56 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/10 - Nightwish - The Crow, The Owl And The Dove.mp3 Apr 14 09:57:56 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F11%20-%20Nightwish%20-%20Last%20Ride%20Of%20The%20Day.mp3&metadata=false Apr 14 09:57:56 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/11 - Nightwish - Last Ride Of The Day.mp3 Apr 14 09:57:56 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F12%20-%20Nightwish%20-%20Song%20Of%20Myself.mp3&metadata=false Apr 14 09:57:56 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/12 - Nightwish - Song Of Myself.mp3 Apr 14 09:57:56 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Nightwish/Nightwish%20-%20Imaginaerum/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FNightwish%20-%202011%20-%20Imaginaerum%2F13%20-%20Nightwish%20-%20Imaginaerum.mp3&metadata=false Apr 14 09:57:56 volumio volumio[681]: info: URI /mnt/NAS/muza/Nightwish - 2011 - Imaginaerum/13 - Nightwish - Imaginaerum.mp3 Apr 14 09:58:03 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:58:03 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 09:58:03 volumio volumio[681]: info: CURURI: music-library/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC] Apr 14 09:58:03 volumio volumio[681]: info: Preload queue cleared Apr 14 09:58:05 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 14 09:58:05 volumio volumio[681]: info: CURURI: music-library/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1 Apr 14 09:58:06 volumio volumio[681]: info: Preload queue cleared Apr 14 09:58:06 volumio volumio[681]: info: Preloading song: music-library/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/Paul Simon - The Essential Paul Simon.m3u Apr 14 09:58:06 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/Paul Simon - The Essential Paul Simon.m3u in service mpd Apr 14 09:58:13 volumio volumio[681]: info: CoreCommandRouter::volumioGetState Apr 14 09:58:13 volumio volumio[681]: info: Preload queue cleared Apr 14 09:58:13 volumio volumio[681]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 09:58:13 volumio volumio[681]: info: CoreStateMachine::ClearQueue Apr 14 09:58:13 volumio volumio[681]: info: CoreStateMachine::stop Apr 14 09:58:13 volumio volumio[681]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 09:58:13 volumio volumio[681]: info: CoreStateMachine::stPlaybackTimer Apr 14 09:58:13 volumio volumio[681]: info: CoreStateMachine::updateTrackBlock Apr 14 09:58:13 volumio volumio[681]: info: CorePlayQueue::getTrackBlock Apr 14 09:58:13 volumio volumio[681]: info: CoreStateMachine::pushState Apr 14 09:58:13 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:58:13 volumio volumio[681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 09:58:13 volumio volumio[681]: info: CoreCommandRouter::volumioPushState Apr 14 09:58:14 volumio volumio[681]: info: CoreStateMachine::serviceStop Apr 14 09:58:14 volumio volumio[681]: info: CorePlayQueue::getTrack 0 Apr 14 09:58:14 volumio volumio[681]: info: CoreCommandRouter::serviceStop Apr 14 09:58:14 volumio volumio[681]: info: ControllerMpd::stop Apr 14 09:58:14 volumio volumio[681]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 09:58:14 volumio volumio[681]: info: CorePlayQueue::clearPlayQueue Apr 14 09:58:14 volumio volumio[681]: info: CorePlayQueue::saveQueue Apr 14 09:58:14 volumio volumio[681]: info: CoreCommandRouter::volumioPushQueue Apr 14 09:58:14 volumio volumio[681]: info: CoreStateMachine::addQueueItems Apr 14 09:58:14 volumio volumio[681]: info: CorePlayQueue::addQueueItems Apr 14 09:58:14 volumio volumio[681]: info: Preload queue cleared Apr 14 09:58:14 volumio volumio[681]: info: Adding Item to queue: music-library/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1 Apr 14 09:58:14 volumio volumio[681]: info: Exploding uri music-library/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1 in service mpd Apr 14 09:58:14 volumio volumio[681]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 14 09:58:14 volumio volumio[681]: info: Listing playlists Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F01%20Mother%20and%20Child%20Reunion.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/01 Mother and Child Reunion.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F02%20Loves%20Me%20Like%20a%20Rock.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/02 Loves Me Like a Rock.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F03%20Me%20and%20Julio%20Down%20by%20the%20Schoolyard.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/03 Me and Julio Down by the Schoolyard.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F04%20Duncan.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/04 Duncan.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F05%20Kodachrome.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/05 Kodachrome.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F06%2050%20Ways%20to%20Leave%20Your%20Lover.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/06 50 Ways to Leave Your Lover.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F07%20Slip%20Slidin'%20Away.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/07 Slip Slidin' Away.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon%20with%20Phoebe%20Snow%20and%20The%20Jessy%20Dixon%20Singers/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F08%20Gone%20at%20Last%20(with%20Phoebe%20Snow%20%26%20The%20Jessy%20Dixon%20Singers).flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/08 Gone at Last (with Phoebe Snow & The Jessy Dixon Singers).flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F09%20Something%20so%20Right.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/09 Something so Right.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F10%20Late%20in%20the%20Evening.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/10 Late in the Evening.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F11%20Hearts%20and%20Bones.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/11 Hearts and Bones.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F12%20Take%20Me%20to%20the%20Mardi%20Gras.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/12 Take Me to the Mardi Gras.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon%20with%20Good%20Rockin'%20Dopsie%20And%20The%20Twisters/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F13%20That%20Was%20Your%20Mother%20(with%20Good%20Rockin'%20Doopsie%20%26%20The%20Twisters).flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/13 That Was Your Mother (with Good Rockin' Doopsie & The Twisters).flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F14%20American%20Tune.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/14 American Tune.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F15%20Peace%20Like%20a%20River.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/15 Peace Like a River.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F16%20Stranded%20in%20a%20Limosine.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/16 Stranded in a Limosine.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F17%20Train%20In%20the%20Distance.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/17 Train In the Distance.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F18%20The%20Late%20Great%20Johnny%20Ace.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/18 The Late Great Johnny Ace.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2F19%20Still%20Crazy%20After%20All%20These%20Years.flac&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/19 Still Crazy After All These Years.flac Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon.cue&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/Paul Simon - The Essential Paul Simon.cue Apr 14 09:58:14 volumio volumio[681]: info: ALBUMART /albumart?cacheid=849&web=Paul%20Simon/The%20Essential%20Paul%20Simon/extralarge&path=%2Fmnt%2FNAS%2Fmuza%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon%20(2007)%20%5BFLAC%5D%2FCD1%2FPaul%20Simon%20-%20The%20Essential%20Paul%20Simon.cue&metadata=false Apr 14 09:58:14 volumio volumio[681]: info: URI /mnt/NAS/muza/Paul Simon - The Essential Paul Simon (2007) [FLAC]/CD1/Paul Simon - The Essential Paul Simon.cue Apr 14 09:58:14 volumio volumio[681]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 09:58:14 volumio volumio[681]: Error: Unable to resolve or reject the same promise twice Apr 14 09:58:14 volumio volumio[681]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 09:58:14 volumio volumio[681]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 14 09:58:14 volumio volumio[681]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 14 09:58:14 volumio volumio[681]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 14 09:58:14 volumio volumio[681]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 14 09:58:14 volumio volumio[681]: at Socket.emit (events.js:400:28) Apr 14 09:58:14 volumio volumio[681]: at addChunk (internal/streams/readable.js:293:12) Apr 14 09:58:14 volumio volumio[681]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 14 09:58:14 volumio volumio[681]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 14 09:58:14 volumio volumio[681]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 09:58:14 volumio volumio[681]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 09:58:14 volumio sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-14 09:57 Apr 14 09:58:14 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"