-- 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"