-- Logs begin at Tue 2024-07-23 11:15:23 UTC, end at Tue 2024-07-23 13:48:59 UTC. -- Jul 23 13:47:01 volumio volumio[13856]: info: CoreCommandRouter::volumioPlay Jul 23 13:47:01 volumio volumio[13856]: info: CoreStateMachine::play index undefined Jul 23 13:47:01 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:01 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:01 volumio volumio[13856]: info: CoreStateMachine::startPlaybackTimer Jul 23 13:47:01 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:01 volumio volumio[13856]: info: [1721742421153] ControllerWebradio::clearAddPlayTrack Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand stop took 2 milliseconds Jul 23 13:47:01 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:01 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:01 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:01 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand clear took 1 milliseconds Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1728664" Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:01 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:01 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:01 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:01 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:01 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:01 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:01 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:01 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:01 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:01 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand status took 3 milliseconds Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:01 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:01 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:01 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:01 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:01 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:01 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:01 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:01 volumio volumio[13856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 6ms Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 6ms Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:01 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1728664" took 245 milliseconds Jul 23 13:47:01 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 23 13:47:01 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand play Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 244ms Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 244ms Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:01 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:01 volumio volumio[13856]: info: Jul 23 13:47:01 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:01 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:01 volumio volumio[13856]: info: sendMpdCommand play took 2 milliseconds Jul 23 13:47:01 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:02 volumio volumio[13856]: info: Jul 23 13:47:02 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:02 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:02 volumio volumio[13856]: info: Jul 23 13:47:02 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:02 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:02 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:02 volumio volumio[13856]: info: Jul 23 13:47:02 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:02 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:02 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:02 volumio volumio[13856]: info: ------------------------------ 0ms Jul 23 13:47:06 volumio volumio[13856]: info: Jul 23 13:47:06 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:06 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:06 volumio volumio[13856]: info: Jul 23 13:47:06 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:06 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:06 volumio volumio[13856]: info: Jul 23 13:47:06 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:06 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:06 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:06 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:07 volumio volumio[13856]: info: Jul 23 13:47:07 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:07 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:07 volumio volumio[13856]: info: Jul 23 13:47:07 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:07 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:07 volumio volumio[13856]: info: Jul 23 13:47:07 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:07 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:07 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:07 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:07 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:08 volumio volumio[13856]: info: CoreCommandRouter::volumioPlay Jul 23 13:47:08 volumio volumio[13856]: info: CoreStateMachine::play index undefined Jul 23 13:47:08 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:08 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:08 volumio volumio[13856]: info: CoreStateMachine::startPlaybackTimer Jul 23 13:47:08 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:08 volumio volumio[13856]: info: [1721742428729] ControllerWebradio::clearAddPlayTrack Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 13:47:08 volumio volumio[13856]: info: Jul 23 13:47:08 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:08 volumio volumio[13856]: info: sendMpdCommand stop took 2 milliseconds Jul 23 13:47:08 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 13:47:08 volumio volumio[13856]: info: Jul 23 13:47:08 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:08 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:08 volumio volumio[13856]: info: Jul 23 13:47:08 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:08 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:08 volumio volumio[13856]: info: Jul 23 13:47:08 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:08 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:08 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:08 volumio volumio[13856]: info: sendMpdCommand clear took 1 milliseconds Jul 23 13:47:08 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:08 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1728664" Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:08 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:08 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:08 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:08 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:08 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:08 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:08 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:08 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:08 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:08 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:08 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:08 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:08 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:08 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:08 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:08 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:08 volumio volumio[13856]: info: Jul 23 13:47:08 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:08 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:08 volumio volumio[13856]: info: Jul 23 13:47:08 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:08 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:08 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:08 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:08 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 13:47:08 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:08 volumio volumio[13856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 13:47:08 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPlay Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::play index undefined Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:09 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::startPlaybackTimer Jul 23 13:47:09 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:09 volumio volumio[13856]: info: [1721742429457] ControllerWebradio::clearAddPlayTrack Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1728664" took 959 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand play Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 958ms Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand stop took 235 milliseconds Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:09 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand play took 1 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand clear took 0 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 0ms Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1728664" Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:09 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:09 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:09 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand status took 2 milliseconds Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:09 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:09 volumio volumio[13856]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 23 13:47:09 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::syncState stateService stop Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus stop Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: No code Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1728664" took 244 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand play Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 244ms Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand status took 244 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 244ms Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand status took 243 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:09 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:09 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:09 volumio volumio[13856]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 23 13:47:09 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::syncState stateService stop Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus stop Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: No code Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:09 volumio volumio[13856]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 23 13:47:09 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::syncState stateService stop Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus stop Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: No code Jul 23 13:47:09 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:09 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 249ms Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 250ms Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: Jul 23 13:47:09 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:09 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:09 volumio volumio[13856]: info: sendMpdCommand play took 8 milliseconds Jul 23 13:47:09 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:11 volumio volumio[13856]: info: Preload queue cleared Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::ClearQueue Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::stop Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::clearPlayQueue Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::saveQueue Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::volumioPushQueue Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::addQueueItems Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::addQueueItems Jul 23 13:47:11 volumio volumio[13856]: info: Preload queue cleared Jul 23 13:47:11 volumio volumio[13856]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984 Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::volumioPushQueue Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::saveQueue Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::updateTrackBlock Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrackBlock Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::volumioPlay Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::play index 0 Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::stop Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::play index undefined Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::startPlaybackTimer Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:11 volumio volumio[13856]: info: [1721742431585] ControllerWebradio::clearAddPlayTrack Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand stop took 2 milliseconds Jul 23 13:47:11 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:11 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:11 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:11 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand clear took 1 milliseconds Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984" Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:11 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:11 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:11 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:11 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:11 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:11 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:11 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:11 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:11 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 5ms Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:11 volumio volumio[13856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 7ms Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:11 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984" took 245 milliseconds Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 242ms Jul 23 13:47:11 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 23 13:47:11 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand play Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:11 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:11 volumio volumio[13856]: info: Jul 23 13:47:11 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:11 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:11 volumio volumio[13856]: info: sendMpdCommand play took 1 milliseconds Jul 23 13:47:11 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:12 volumio volumio[13856]: info: CoreCommandRouter::volumioPlay Jul 23 13:47:12 volumio volumio[13856]: info: CoreStateMachine::play index undefined Jul 23 13:47:12 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:12 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:12 volumio volumio[13856]: info: CoreStateMachine::startPlaybackTimer Jul 23 13:47:12 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:12 volumio volumio[13856]: info: [1721742432809] ControllerWebradio::clearAddPlayTrack Jul 23 13:47:12 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand stop took 708 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand clear took 1 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984" Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 3ms Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:13 volumio volumio[13856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 5ms Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984" took 244 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 242ms Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 241ms Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand play Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand play took 0 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 0ms Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::volumioPlay Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::play index undefined Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::startPlaybackTimer Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: [1721742433901] ControllerWebradio::clearAddPlayTrack Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand stop took 1 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand clear Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand clear took 1 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984" Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:13 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:13 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:13 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:13 volumio volumio[13856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: info: Jul 23 13:47:13 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:13 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:13 volumio volumio[13856]: error: updateQueue error: null Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:13 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 13:47:13 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:13 volumio volumio[13856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 23 13:47:13 volumio volumio[13856]: info: ------------------------------ 5ms Jul 23 13:47:14 volumio volumio[13856]: info: Jul 23 13:47:14 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:14 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:14 volumio volumio[13856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497984" took 248 milliseconds Jul 23 13:47:14 volumio volumio[13856]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 23 13:47:14 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand play Jul 23 13:47:14 volumio volumio[13856]: info: ------------------------------ 246ms Jul 23 13:47:14 volumio volumio[13856]: info: ------------------------------ 246ms Jul 23 13:47:14 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:14 volumio volumio[13856]: info: Jul 23 13:47:14 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:14 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:14 volumio volumio[13856]: info: Jul 23 13:47:14 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:14 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:14 volumio volumio[13856]: info: sendMpdCommand play took 1 milliseconds Jul 23 13:47:14 volumio volumio[13856]: info: ------------------------------ 0ms Jul 23 13:47:14 volumio volumio[13856]: info: ------------------------------ 0ms Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-oesterreich","artist":"(#1 - 364/500000) ROCK ANTENNE ?sterreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus stop Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-oesterreich","artist":"(#1 - 364/500000) ROCK ANTENNE ?sterreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-oesterreich","artist":"(#1 - 364/500000) ROCK ANTENNE ?sterreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 11ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 12ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 13ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-oesterreich","artist":"(#1 - 364/500000) ROCK ANTENNE ?sterreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-oesterreich","artist":"(#1 - 364/500000) ROCK ANTENNE ?sterreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 13ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 12ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-oesterreich","artist":"(#1 - 364/500000) ROCK ANTENNE ?sterreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 16ms Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 2 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 7ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 8ms Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 6ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 6ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 0 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":307,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 11ms Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:16 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:16 volumio volumio[13856]: info: Jul 23 13:47:16 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 4 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand status took 4 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 23ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 23ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 18ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 18ms Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 23 13:47:16 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:16 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:16 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:16 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:16 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:16 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 20ms Jul 23 13:47:16 volumio volumio[13856]: info: ------------------------------ 21ms Jul 23 13:47:17 volumio volumio[13856]: info: Jul 23 13:47:17 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:17 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:17 volumio volumio[13856]: info: Jul 23 13:47:17 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:17 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:17 volumio volumio[13856]: info: Jul 23 13:47:17 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:17 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:17 volumio volumio[13856]: info: Jul 23 13:47:17 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:17 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:17 volumio volumio[13856]: info: Jul 23 13:47:17 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:17 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:17 volumio volumio[13856]: info: Jul 23 13:47:17 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:17 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:17 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:17 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:17 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:17 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:17 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:17 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:17 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 13:47:17 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:17 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:17 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:17 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:17 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:17 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:17 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:17 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:17 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:17 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:17 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:17 volumio volumio[13856]: info: ------------------------------ 6ms Jul 23 13:47:17 volumio volumio[13856]: info: ------------------------------ 5ms Jul 23 13:47:17 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 13:47:17 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:17 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:17 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:17 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:17 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:17 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:17 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:17 volumio volumio[13856]: info: ------------------------------ 7ms Jul 23 13:47:18 volumio volumio[13856]: info: Jul 23 13:47:18 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:18 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:18 volumio volumio[13856]: info: Jul 23 13:47:18 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:18 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:18 volumio volumio[13856]: info: Jul 23 13:47:18 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:18 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:18 volumio volumio[13856]: info: Jul 23 13:47:18 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:18 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:18 volumio volumio[13856]: info: Jul 23 13:47:18 volumio volumio[13856]: ---------------------------- MPD announces system playlist update Jul 23 13:47:18 volumio volumio[13856]: info: Ignoring MPD Status Update Jul 23 13:47:18 volumio volumio[13856]: info: Jul 23 13:47:18 volumio volumio[13856]: ---------------------------- MPD announces state update: player Jul 23 13:47:18 volumio volumio[13856]: info: ControllerMpd::getState Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand status Jul 23 13:47:18 volumio volumio[13856]: info: ------------------------------ 2ms Jul 23 13:47:18 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:18 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:18 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:18 volumio volumio[13856]: info: ------------------------------ 1ms Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:18 volumio volumio[13856]: info: sendMpdCommand status took 1 milliseconds Jul 23 13:47:18 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::parseState Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:18 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:18 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:18 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:18 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:18 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:18 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:18 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:18 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:18 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:18 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:18 volumio volumio[13856]: info: ------------------------------ 4ms Jul 23 13:47:18 volumio volumio[13856]: info: ------------------------------ 5ms Jul 23 13:47:18 volumio volumio[13856]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 23 13:47:18 volumio volumio[13856]: verbose: ControllerMpd::parseTrackInfo Jul 23 13:47:18 volumio volumio[13856]: info: ControllerMpd::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::servicePushState Jul 23 13:47:18 volumio volumio[13856]: info: CorePlayQueue::getTrack 0 Jul 23 13:47:18 volumio volumio[13856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Henley - The Boys Of Summer","artist":"ROCK ANTENNE Österreich","album":null,"uri":"http://stream.antenne.de:80/rockantenne-oesterreich","trackType":""} Jul 23 13:47:18 volumio volumio[13856]: verbose: CURRENT POSITION 0 Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::syncState stateService play Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::syncState currentStatus play Jul 23 13:47:18 volumio volumio[13856]: info: Received an update from plugin. extracting info from payload Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:18 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:47:18 volumio volumio[13856]: info: ------------------------------ 6ms Jul 23 13:47:43 volumio volumio[13856]: info: VolumeController::SetAlsaVolume64 Jul 23 13:47:43 volumio volumio[13856]: info: CoreStateMachine::pushState Jul 23 13:47:43 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 13:47:43 volumio volumio[13856]: info: CoreCommandRouter::volumioPushState Jul 23 13:48:27 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 23 13:48:27 volumio volumio[13856]: info: Preload queue cleared Jul 23 13:48:31 volumio nmbd[969]: [2024/07/23 13:48:31.139755, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 23 13:48:31 volumio nmbd[969]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.50 for name WORKGROUP<1d>. Jul 23 13:48:31 volumio nmbd[969]: This response was from IP 192.168.1.1, reporting an IP address of 192.168.1.1. Jul 23 13:48:35 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 23 13:48:35 volumio volumio[13856]: info: Preload queue cleared Jul 23 13:48:43 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 23 13:48:43 volumio volumio[13856]: info: TuneIn handleBrowseUri: tunein/location Jul 23 13:48:46 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 23 13:48:46 volumio volumio[13856]: info: TuneIn handleBrowseUri: tunein/location Jul 23 13:48:52 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 23 13:48:52 volumio volumio[13856]: info: TuneIn handleBrowseUri: tunein/popular Jul 23 13:48:53 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 23 13:48:53 volumio volumio[13856]: info: TuneIn handleBrowseUri: tunein/popular Jul 23 13:48:54 volumio volumio[13856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 23 13:48:54 volumio volumio[13856]: info: TuneIn handleBrowseUri: tunein/popular Jul 23 13:48:59 volumio volumio[13856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 13:48:59 volumio volumio[13856]: Error: connect ETIMEDOUT 168.143.171.186:80 Jul 23 13:48:59 volumio volumio[13856]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 23 13:48:59 volumio volumio[13856]: errno: -110, Jul 23 13:48:59 volumio volumio[13856]: code: 'ETIMEDOUT', Jul 23 13:48:59 volumio volumio[13856]: syscall: 'connect', Jul 23 13:48:59 volumio volumio[13856]: address: '168.143.171.186', Jul 23 13:48:59 volumio volumio[13856]: port: 80 Jul 23 13:48:59 volumio volumio[13856]: } Jul 23 13:48:59 volumio volumio[13856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 13:48:59 volumio sudo[19724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 13:47 Jul 23 13:48:59 volumio sudo[19724]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"