-- Logs begin at Fri 2024-03-08 19:29:16 CET, end at Mon 2024-04-29 17:00:13 CEST. -- Apr 29 16:59:02 volumiot2 volumio[658]: info: Apr 29 16:59:02 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:02 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:02 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:02 volumiot2 volumio[658]: info: Apr 29 16:59:02 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:02 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:02 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:02 volumiot2 volumio[658]: info: Apr 29 16:59:02 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:02 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:02 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:03 volumiot2 volumio[658]: info: Apr 29 16:59:03 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand status took 907 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand status took 907 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand status took 906 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand status took 1 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:03 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":373,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:03 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:03 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: ------------------------------ 920ms Apr 29 16:59:03 volumiot2 volumio[658]: info: Apr 29 16:59:03 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:03 volumiot2 volumio[658]: info: Apr 29 16:59:03 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:03 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":3,"seek":21,"duration":373,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:03 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:03 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:03 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":3,"seek":42,"duration":373,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:03 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:03 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:03 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":3,"seek":262,"duration":373,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"167 Kbps","isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:03 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:03 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: ------------------------------ 948ms Apr 29 16:59:03 volumiot2 volumio[658]: info: ------------------------------ 949ms Apr 29 16:59:03 volumiot2 volumio[658]: info: ------------------------------ 45ms Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:03 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:03 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":3,"seek":986,"duration":373,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"691 Kbps","isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:03 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:03 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:03 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:03 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":3,"seek":986,"duration":373,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"691 Kbps","isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:03 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:03 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:03 volumiot2 volumio[658]: info: ------------------------------ 82ms Apr 29 16:59:03 volumiot2 volumio[658]: info: ------------------------------ 83ms Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:03 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 16:59:03 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 16:59:03 volumiot2 volumio[658]: info: Consume mode Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 16:59:03 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: Apr 29 16:59:04 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:04 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:04 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:30 volumiot2 volumio[658]: info: Apr 29 16:59:30 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:30 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:30 volumiot2 volumio[658]: info: Apr 29 16:59:30 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:30 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:30 volumiot2 volumio[658]: info: Apr 29 16:59:30 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:30 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:30 volumiot2 volumio[658]: info: sendMpdCommand status took 1 milliseconds Apr 29 16:59:30 volumiot2 volumio[658]: info: sendMpdCommand status took 1 milliseconds Apr 29 16:59:30 volumiot2 volumio[658]: info: sendMpdCommand status took 1 milliseconds Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:30 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 16:59:30 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 16:59:30 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:30 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:30 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:30 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"stop","position":3,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:30 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService stop Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::stPlaybackTimer Apr 29 16:59:30 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:30 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"stop","position":3,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:30 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService stop Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: No code Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:30 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"stop","position":3,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost in Translation","artist":"Céline Bonacina","album":"Jump!","uri":"http://192.168.1.55:58050/proxy/8217cf11123e44e86187693709d2cb21.flac","trackType":"flac"} Apr 29 16:59:30 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService stop Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: No code Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:30 volumiot2 volumio[658]: info: ------------------------------ 25ms Apr 29 16:59:30 volumiot2 volumio[658]: info: ------------------------------ 25ms Apr 29 16:59:30 volumiot2 volumio[658]: info: ------------------------------ 26ms Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetQueue Apr 29 16:59:30 volumiot2 volumio[658]: info: CoreStateMachine::getQueue Apr 29 16:59:30 volumiot2 volumio[658]: info: CorePlayQueue::getQueue Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:30 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:47 volumiot2 volumio[658]: info: Apr 29 16:59:47 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:47 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:47 volumiot2 volumio[658]: info: Apr 29 16:59:47 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:47 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:47 volumiot2 volumio[658]: info: Apr 29 16:59:47 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:47 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:47 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 16:59:47 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 16:59:47 volumiot2 volumio[658]: info: Consume mode Apr 29 16:59:47 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 16:59:47 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 16:59:47 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:47 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:51 volumiot2 volumio[658]: info: Clearing queue after UPNP request Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreStateMachine::ClearQueue Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreStateMachine::stop Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 16:59:51 volumiot2 volumio[658]: info: CorePlayQueue::clearPlayQueue Apr 29 16:59:51 volumiot2 volumio[658]: info: CorePlayQueue::saveQueue Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushQueue Apr 29 16:59:51 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:51 volumiot2 volumio[658]: info: Apr 29 16:59:51 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:51 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:51 volumiot2 volumio[658]: info: Apr 29 16:59:51 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:51 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:51 volumiot2 volumio[658]: info: Apr 29 16:59:51 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:51 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:51 volumiot2 volumio[658]: error: updateQueue error: null Apr 29 16:59:51 volumiot2 volumio[658]: error: updateQueue error: null Apr 29 16:59:51 volumiot2 volumio[658]: error: updateQueue error: null Apr 29 16:59:51 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:51 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:51 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 16:59:51 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 16:59:51 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:51 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 4ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 16:59:52 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 1ms Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand status took 6 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand status took 5 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand status took 4 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:52 volumiot2 volumio[658]: info: Apr 29 16:59:52 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand status took 4 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand status took 6 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand status took 6 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:52 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:52 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:52 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:52 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:52 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:52 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:52 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:52 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 27ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 29ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 30ms Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:52 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:52 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:52 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:52 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:52 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:52 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:52 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:52 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 16:59:52 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:52 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 16:59:52 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:52 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 41ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 39ms Apr 29 16:59:52 volumiot2 volumio[658]: info: ------------------------------ 38ms Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:52 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 16:59:53 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 16:59:53 volumiot2 volumio[658]: info: Consume mode Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioStop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::stPlaybackTimer Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::updateTrackBlock Apr 29 16:59:53 volumiot2 volumio[658]: info: CorePlayQueue::getTrackBlock Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::serviceStop Apr 29 16:59:53 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::stop Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: info: Apr 29 16:59:53 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand stop took 5 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand status took 0 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:53 volumiot2 volumio[658]: info: Apr 29 16:59:53 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:53 volumiot2 volumio[658]: info: Apr 29 16:59:53 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand status took 2 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand status took 1 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:53 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:53 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: No code Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: ------------------------------ 11ms Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:53 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:53 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:53 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: No code Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 16:59:53 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"[WDR2] WDR 2 Bergischesland","artist":null,"album":null,"uri":"http://192.168.1.55:9790/minimstreamer/*/WDR2/$!stream.mp3","trackType":"mp3"} Apr 29 16:59:53 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: No code Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 16:59:53 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 16:59:53 volumiot2 volumio[658]: info: ------------------------------ 23ms Apr 29 16:59:53 volumiot2 volumio[658]: info: ------------------------------ 22ms Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 16:59:53 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:07 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 17:00:07 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 17:00:07 volumiot2 volumio[658]: info: Consume mode Apr 29 17:00:07 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 17:00:07 volumiot2 volumio[658]: info: CoreCommandRouter::volumioStop Apr 29 17:00:07 volumiot2 volumio[658]: info: CoreStateMachine::stop Apr 29 17:00:07 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:00:07 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 17:00:10 volumiot2 volumio[658]: info: Clearing queue after UPNP request Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreStateMachine::ClearQueue Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreStateMachine::stop Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:00:10 volumiot2 volumio[658]: info: CorePlayQueue::clearPlayQueue Apr 29 17:00:10 volumiot2 volumio[658]: info: CorePlayQueue::saveQueue Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushQueue Apr 29 17:00:10 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:10 volumiot2 volumio[658]: info: Apr 29 17:00:10 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:10 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:10 volumiot2 volumio[658]: info: Apr 29 17:00:10 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:10 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:10 volumiot2 volumio[658]: info: Apr 29 17:00:10 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:10 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:10 volumiot2 volumio[658]: error: updateQueue error: null Apr 29 17:00:10 volumiot2 volumio[658]: error: updateQueue error: null Apr 29 17:00:10 volumiot2 volumio[658]: error: updateQueue error: null Apr 29 17:00:10 volumiot2 volumio[658]: info: ------------------------------ 5ms Apr 29 17:00:10 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 17:00:10 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:10 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 17:00:10 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 17:00:10 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 17:00:10 volumiot2 volumio[658]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 6ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 5ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 3ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand status took 2 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:11 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WDR 2 Stream","artist":"WDR 2","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:11 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus stop Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:11 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WDR 2 Stream","artist":"WDR 2","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:11 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:11 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:11 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WDR 2 Stream","artist":"WDR 2","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:11 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:11 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 19ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 22ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 22ms Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:11 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WDR 2 Stream","artist":"WDR 2","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:11 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:11 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:11 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WDR 2 Stream","artist":"WDR 2","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:11 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:11 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:11 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WDR 2 Stream","artist":"WDR 2","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:11 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:11 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:11 volumiot2 volumio[658]: info: CoreCommandRouter::volumioPushState Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 32ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 31ms Apr 29 17:00:11 volumiot2 volumio[658]: info: ------------------------------ 31ms Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:11 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:11 volumiot2 volumio[658]: info: Apr 29 17:00:11 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:11 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:11 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:12 volumiot2 volumio[658]: info: Apr 29 17:00:12 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:12 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:12 volumiot2 volumio[658]: info: Apr 29 17:00:12 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:12 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:12 volumiot2 volumio[658]: info: Apr 29 17:00:12 volumiot2 volumio[658]: ---------------------------- MPD announces system playlist update Apr 29 17:00:12 volumiot2 volumio[658]: info: Ignoring MPD Status Update Apr 29 17:00:12 volumiot2 volumio[658]: info: Apr 29 17:00:12 volumiot2 volumio[658]: ---------------------------- MPD announces state update: player Apr 29 17:00:12 volumiot2 volumio[658]: info: ControllerMpd::getState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:00:12 volumiot2 volumio[658]: info: ------------------------------ 6ms Apr 29 17:00:12 volumiot2 volumio[658]: info: sendMpdCommand status took 5 milliseconds Apr 29 17:00:12 volumiot2 volumio[658]: info: ------------------------------ 4ms Apr 29 17:00:12 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 17:00:12 volumiot2 volumio[658]: info: ------------------------------ 2ms Apr 29 17:00:12 volumiot2 volumio[658]: info: sendMpdCommand status took 3 milliseconds Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::parseState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:00:12 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 17:00:12 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 17:00:12 volumiot2 volumio[658]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:12 volumiot2 volumio[658]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:00:12 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:12 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.mp3","artist":"WDR 2 Bergisches Land aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:12 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:12 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:12 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 17:00:12 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:12 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.mp3","artist":"WDR 2 Bergisches Land aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:12 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:12 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:12 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 17:00:12 volumiot2 volumio[658]: info: ControllerMpd::pushState Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::servicePushState Apr 29 17:00:12 volumiot2 volumio[658]: verbose: In UPNP mode Apr 29 17:00:12 volumiot2 volumio[658]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.mp3","artist":"WDR 2 Bergisches Land aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"http://192.168.1.1:49200/ST/AUDIO/DLNA-1-0/wdr-wdr2-bergischesland.icecast.wdr.de/wdr/wdr2/bergischesland/mp3/128/stream.mp3","trackType":"mp3"} Apr 29 17:00:12 volumiot2 volumio[658]: verbose: CURRENT POSITION 0 Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::syncState stateService play Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:00:12 volumiot2 volumio[658]: info: Received an update from plugin. extracting info from payload Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreStateMachine::pushState Apr 29 17:00:12 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 17:00:12 volumiot2 volumio[658]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 29 17:00:12 volumiot2 volumio[658]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Apr 29 17:00:12 volumiot2 volumio[658]: info: ------------------------------ 15ms Apr 29 17:00:12 volumiot2 volumio[658]: info: ------------------------------ 14ms Apr 29 17:00:12 volumiot2 volumio[658]: info: ------------------------------ 12ms Apr 29 17:00:12 volumiot2 volumio[658]: info: Starting UPNP Playback Apr 29 17:00:12 volumiot2 volumio[658]: info: Preparing playback through UPNP Apr 29 17:00:12 volumiot2 volumio[658]: info: Consume mode Apr 29 17:00:12 volumiot2 volumio[658]: info: CoreCommandRouter::volumioGetState Apr 29 17:00:12 volumiot2 volumio[658]: info: CorePlayQueue::getTrack 0 Apr 29 17:00:12 volumiot2 volumio[658]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 17:00:12 volumiot2 volumio[658]: TypeError: Cannot read property 'name' of undefined Apr 29 17:00:12 volumiot2 volumio[658]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Apr 29 17:00:12 volumiot2 volumio[658]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Apr 29 17:00:12 volumiot2 volumio[658]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Apr 29 17:00:12 volumiot2 volumio[658]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Apr 29 17:00:12 volumiot2 volumio[658]: at Socket.emit (events.js:400:28) Apr 29 17:00:12 volumiot2 volumio[658]: at addChunk (internal/streams/readable.js:293:12) Apr 29 17:00:12 volumiot2 volumio[658]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 29 17:00:12 volumiot2 volumio[658]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 29 17:00:12 volumiot2 volumio[658]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Apr 29 17:00:12 volumiot2 volumio[658]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 17:00:13 volumiot2 sudo[22437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 16:59 Apr 29 17:00:13 volumiot2 sudo[22437]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"