Mar 26 07:46:03 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::ClearQueue Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 4 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::serviceStop Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 4 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::serviceStop Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::stop Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::clearPlayQueue Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:03 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/155 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/155 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/156 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/156 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/157 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/157 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/158 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/158 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/159 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/159 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/160 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/160 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPlay Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::play index 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:03 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/161 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/161 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/3 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/3 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/7 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/7 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/10 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/10 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/19 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/19 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/28 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/28 Mar 26 07:46:03 volumio volumio[1365]: info: Adding Item to queue: webcarrot/130 Mar 26 07:46:03 volumio volumio[1365]: info: Using cached record of: webcarrot/130 Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::play index undefined Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand stop took 92 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand stop took 53 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 43 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 41 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand clear took 40 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 28 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 28 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 27 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 21 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand status took 20 milliseconds Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "https://mscp3.live-streams.nl:8362/flac.flac" Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:03 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 71ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 71ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 70ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 70ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 64ms Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: error: updateQueue error: null Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 94ms Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 88 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 88 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand add "https://mscp3.live-streams.nl:8362/flac.flac" took 80 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 44ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 42ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 41ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 40ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 39ms Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play Mar 26 07:46:03 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 158ms Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: Mar 26 07:46:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:03 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:03 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 58ms Mar 26 07:46:03 volumio volumio[1365]: info: sendMpdCommand play took 33 milliseconds Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 32ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 31ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 30ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 29ms Mar 26 07:46:03 volumio volumio[1365]: info: ------------------------------ 25ms Mar 26 07:46:03 volumio volumio[1365]: info: [clearAddPlayTrack] title=Naim Radio program=no Mar 26 07:46:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 07:46:03 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 20 seconds. Mar 26 07:46:03 volumio volumio[1365]: info: Pushing Favourites {"uri":"webcarrot/160","favourite":false} Mar 26 07:46:03 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:03 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:03 volumio volumio[1365]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received carrot_radio Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 202 milliseconds Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 204 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 203 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 203 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 202 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 201 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 208ms Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: Mar 26 07:46:05 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 7 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 241ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 241ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 241ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 241ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 241ms Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand status took 37 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 79ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 80ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 78ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 77ms Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 78ms Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 98 milliseconds Mar 26 07:46:05 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:05 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:05 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:05 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:05 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:05 volumio volumio[1365]: info: ------------------------------ 143ms Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:05 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:06 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:06 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:06 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:06 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:06 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:06 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:06 volumio volumio[1365]: info: Mar 26 07:46:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 6ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 5ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand status took 4 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 4ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 4ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 3ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 4ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":641,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:06 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:06 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:06 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:06 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:06 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 38ms Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 38ms Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 37ms Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 36ms Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 37ms Mar 26 07:46:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 26 07:46:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:06 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"https://mscp3.live-streams.nl:8362/flac.flac","trackType":"flac"} Mar 26 07:46:06 volumio volumio[1365]: verbose: CURRENT POSITION 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:06 volumio volumio[1365]: info: ------------------------------ 46ms Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:06 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::ClearQueue Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::serviceStop Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 5 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::serviceStop Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::stop Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::clearPlayQueue Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:25 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/155 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/155 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/156 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/156 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/157 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/157 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/158 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/158 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/159 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/159 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/160 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/160 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/161 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/161 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPlay Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::play index 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:25 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/3 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/3 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/7 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/7 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/10 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/10 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/19 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/19 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/28 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/28 Mar 26 07:46:25 volumio volumio[1365]: info: Adding Item to queue: webcarrot/130 Mar 26 07:46:25 volumio volumio[1365]: info: Using cached record of: webcarrot/130 Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::play index undefined Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:25 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand stop took 44 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand stop took 22 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 15 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 15 milliseconds Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand clear took 16 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 13 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 13 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 13 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 12 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand status took 11 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "https://mscp3.live-streams.nl:8342/jazz-flac.flac" Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:25 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:25 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 50ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 49ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 48ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 48ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 47ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 59ms Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 20 seconds. Mar 26 07:46:25 volumio volumio[1365]: error: updateQueue error: null Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 50ms Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand add "https://mscp3.live-streams.nl:8342/jazz-flac.flac" took 49 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 10ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 9ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 10ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 8ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 6ms Mar 26 07:46:25 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: Mar 26 07:46:25 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:25 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:25 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 39ms Mar 26 07:46:25 volumio volumio[1365]: info: sendMpdCommand play took 32 milliseconds Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 31ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 30ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 28ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 27ms Mar 26 07:46:25 volumio volumio[1365]: info: ------------------------------ 26ms Mar 26 07:46:25 volumio volumio[1365]: info: [clearAddPlayTrack] title=Naim Jazz program=no Mar 26 07:46:25 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 26 07:46:25 volumio volumio[1365]: info: Pushing Favourites {"uri":"webcarrot/161","favourite":false} Mar 26 07:46:25 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:25 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:25 volumio volumio[1365]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received carrot_radio Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 203 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 202 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 201 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 203 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 202 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 202 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 3 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 224ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 233ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 236ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 236ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 236ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 235ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 34 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 35 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 34 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 70ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 68ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 68ms Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 76 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 76 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 75 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 133ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 134ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 134ms Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:27 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:27 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:27 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:27 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:27 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:27 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:27 volumio volumio[1365]: info: Mar 26 07:46:27 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 9ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 8 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 7ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 7ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 5ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 6ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 5ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand status took 4 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":618,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 36ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 36ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 34ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 33ms Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 33ms Mar 26 07:46:27 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 26 07:46:27 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:27 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"https://mscp3.live-streams.nl:8342/jazz-flac.flac","trackType":"flac"} Mar 26 07:46:27 volumio volumio[1365]: verbose: CURRENT POSITION 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:27 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:27 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:27 volumio volumio[1365]: info: ------------------------------ 45ms Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:27 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=webradio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::ClearQueue Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::serviceStop Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 6 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::serviceStop Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::stop Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::clearPlayQueue Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:36 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/155 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/155 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/156 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/156 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/157 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/157 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/158 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/158 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/159 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/159 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/160 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/160 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/161 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/161 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/3 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/3 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/7 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/7 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/10 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/10 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/19 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/19 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPlay Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::play index 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:36 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/28 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/28 Mar 26 07:46:36 volumio volumio[1365]: info: Adding Item to queue: webcarrot/130 Mar 26 07:46:36 volumio volumio[1365]: info: Using cached record of: webcarrot/130 Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::play index undefined Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:36 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand stop took 53 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand stop took 31 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 17 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 17 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand clear took 17 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 16 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 15 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 15 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 14 milliseconds Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8" Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 44ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 45ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 44ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 44ms Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: error: updateQueue error: null Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand status took 46 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 37ms Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8" took 35 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 6ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 4ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 2ms Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:36 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play Mar 26 07:46:36 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:36 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:36 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 60ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 64ms Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:36 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 20 seconds. Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 66ms Mar 26 07:46:36 volumio volumio[1365]: info: sendMpdCommand play took 61 milliseconds Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 55ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 54ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 45ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 44ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 3ms Mar 26 07:46:36 volumio volumio[1365]: info: [clearAddPlayTrack] title=CBS 음악FM program=yes Mar 26 07:46:36 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:36 volumio volumio[1365]: info: [startProgramRefresh] CBS 음악FM Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Mar 26 07:46:36 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:36 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:36 volumio volumio[1365]: info: Pushing Favourites {"uri":"webcarrot/19","favourite":false} Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 7ms Mar 26 07:46:36 volumio volumio[1365]: info: ------------------------------ 6ms Mar 26 07:46:37 volumio volumio[1365]: info: [fetchProgramInfo] https://station.zstream.win/api/onair/program?stn=cbs&ch=musicfm -> 김용신의 그대와 여는 아침 Mar 26 07:46:37 volumio volumio[1365]: info: [programRefresh] fetched: 김용신의 그대와 여는 아침 Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 2 milliseconds Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: [programRefresh] queue updated Mar 26 07:46:37 volumio volumio[1365]: info: [programRefresh] pushed: status=play program=김용신의 그대와 여는 아침 Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS 음악FM","artist":"김용신의 그대와 여는 아침","album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8","service":"carrot_radio","albumart":"https://static.mytuner.mobi/media/tvos_radios/593/eumagfm-cbs-radio-music-fm.0f29a25a.png"} Mar 26 07:46:37 volumio volumio[1365]: verbose: CURRENT POSITION 10 Mar 26 07:46:37 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:37 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 213 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 211 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 211 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 210 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 208 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 207 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 206 milliseconds Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: Mar 26 07:46:37 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 10 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 9 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 8 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 4 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 4 milliseconds Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: [programRefresh] ICY override -> status=play program=김용신의 그대와 여는 아침 Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 231ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 226ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 225ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 223ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 222ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 221ms Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand status took 8 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: info: [programRefresh] ICY override -> status=play program=김용신의 그대와 여는 아침 Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 20ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 19ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 14ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 13ms Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 12ms Mar 26 07:46:37 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:37 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:37 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:37 volumio volumio[1365]: info: ------------------------------ 13ms Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CBS 음악FM","artist":"김용신의 그대와 여는 아침","album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8","trackType":"m3u8","service":"carrot_radio","albumart":"https://static.mytuner.mobi/media/tvos_radios/593/eumagfm-cbs-radio-music-fm.0f29a25a.png"} Mar 26 07:46:37 volumio volumio[1365]: verbose: CURRENT POSITION 10 Mar 26 07:46:37 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:37 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:46:37 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:46:37 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:37 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:37 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:37 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:37 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=carrot_radio volatile=false Mar 26 07:46:37 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::ClearQueue Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::stPlaybackTimer Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::serviceStop Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 10 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::serviceStop Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::stop Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::clearPlayQueue Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:48 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/155 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/155 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/156 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/156 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/157 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/157 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/158 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/158 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/159 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/159 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/160 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/160 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/161 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/161 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/3 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/3 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPlay Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::play index 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::addQueueItems Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::addQueueItems Mar 26 07:46:48 volumio volumio[1365]: info: Preload queue cleared Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/7 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/7 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/10 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/10 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/19 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/19 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/28 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/28 Mar 26 07:46:48 volumio volumio[1365]: info: Adding Item to queue: webcarrot/130 Mar 26 07:46:48 volumio volumio[1365]: info: Using cached record of: webcarrot/130 Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::stop Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushQueue Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::saveQueue Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::play index undefined Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::updateTrackBlock Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrackBlock Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 07:46:48 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand stop took 60 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand stop took 24 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 19 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 18 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand clear took 18 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 17 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 16 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 15 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 14 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand status took 13 milliseconds Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTExODl9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=RGnOaVVT4XABkyCbC1aXgm-rekafNs4uo6XZaPkRQqvpbtdiJcYrIM-muR1M2aagCr4a9s9mU2bszqP8TaTq8Uz99fnI7dCJUw6IdN-BEnOzKRnxHrbgbPcK0hNFSrWkWW282~IcuUqmmtadU1GzGj5tiq75SWX0gckJE7g7AL8qokXmiDk4NMDeWoOgcBZdaXubQ5gWTaB1OvW6r7Ugmk0jfGoIvN94i7m9-L8O1vt2Elnl4w8ueM7EFTZSbtBXelFlEckwRd4t1-7jOe6MPVkqijkhoEnRhucJ5nly5MM9jtAM0ZK7C~5bPoAZiBfSEqSOTiSd0kPryI6Q8OscTQ__" Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:48 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:48 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 47ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 47ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 46ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 46ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 46ms Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: error: updateQueue error: null Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 42ms Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTExODl9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=RGnOaVVT4XABkyCbC1aXgm-rekafNs4uo6XZaPkRQqvpbtdiJcYrIM-muR1M2aagCr4a9s9mU2bszqP8TaTq8Uz99fnI7dCJUw6IdN-BEnOzKRnxHrbgbPcK0hNFSrWkWW282~IcuUqmmtadU1GzGj5tiq75SWX0gckJE7g7AL8qokXmiDk4NMDeWoOgcBZdaXubQ5gWTaB1OvW6r7Ugmk0jfGoIvN94i7m9-L8O1vt2Elnl4w8ueM7EFTZSbtBXelFlEckwRd4t1-7jOe6MPVkqijkhoEnRhucJ5nly5MM9jtAM0ZK7C~5bPoAZiBfSEqSOTiSd0kPryI6Q8OscTQ__" took 41 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 9ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 8ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 7ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 6ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 5ms Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:48 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play Mar 26 07:46:48 volumio volumio[1365]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 67ms Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: Mar 26 07:46:48 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Mar 26 07:46:48 volumio volumio[1365]: info: Ignoring MPD Status Update Mar 26 07:46:48 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false Mar 26 07:46:48 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 20 seconds. Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 34ms Mar 26 07:46:48 volumio volumio[1365]: info: sendMpdCommand play took 28 milliseconds Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 28ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 27ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 26ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 25ms Mar 26 07:46:48 volumio volumio[1365]: info: ------------------------------ 25ms Mar 26 07:46:48 volumio volumio[1365]: info: [clearAddPlayTrack] title=KBS ClassicFM program=yes Mar 26 07:46:48 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:48 volumio volumio[1365]: info: [startProgramRefresh] KBS ClassicFM Mar 26 07:46:48 volumio volumio[1365]: info: Pushing Favourites {"uri":"webcarrot/3","favourite":false} Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 202 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 203 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 203 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 201 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 200 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 199 milliseconds Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: Mar 26 07:46:49 volumio volumio[1365]: ---------------------------- MPD announces state update: player Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 8 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 7 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 5 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 219ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 217ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 216ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 214ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 213ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 212ms Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::pushState Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 19ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 18ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 14ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 14ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 13ms Mar 26 07:46:49 volumio volumio[1365]: info: ------------------------------ 12ms Mar 26 07:46:49 volumio volumio[1365]: info: [fetchProgramInfo] https://radio.yuntae.in/api/onair/program?stn=kbs&ch=1fm -> 출발 FM과 함께 Mar 26 07:46:49 volumio volumio[1365]: info: [programRefresh] fetched: 출발 FM과 함께 Mar 26 07:46:49 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:46:49 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 26 07:46:49 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: info: [programRefresh] queue updated Mar 26 07:46:49 volumio volumio[1365]: info: [programRefresh] pushed: status=play program=출발 FM과 함께 Mar 26 07:46:49 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:46:49 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:46:49 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1177,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"196 Kbps","isStreaming":false,"title":"KBS ClassicFM","artist":"출발 FM과 함께","album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTExODl9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=RGnOaVVT4XABkyCbC1aXgm-rekafNs4uo6XZaPkRQqvpbtdiJcYrIM-muR1M2aagCr4a9s9mU2bszqP8TaTq8Uz99fnI7dCJUw6IdN-BEnOzKRnxHrbgbPcK0hNFSrWkWW282~IcuUqmmtadU1GzGj5tiq75SWX0gckJE7g7AL8qokXmiDk4NMDeWoOgcBZdaXubQ5gWTaB1OvW6r7Ugmk0jfGoIvN94i7m9-L8O1vt2Elnl4w8ueM7EFTZSbtBXelFlEckwRd4t1-7jOe6MPVkqijkhoEnRhucJ5nly5MM9jtAM0ZK7C~5bPoAZiBfSEqSOTiSd0kPryI6Q8OscTQ__","trackType":"","service":"carrot_radio","albumart":"https://padmin.static.kbs.co.kr/live/2021/5/28/1622175310040_252323.jpg"} Mar 26 07:46:49 volumio volumio[1365]: verbose: CURRENT POSITION 7 Mar 26 07:46:49 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:46:49 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Mar 26 07:47:19 volumio volumio[1365]: info: [fetchProgramInfo] https://radio.yuntae.in/api/onair/program?stn=kbs&ch=1fm -> 출발 FM과 함께 Mar 26 07:47:19 volumio volumio[1365]: info: [programRefresh] fetched: 출발 FM과 함께 Mar 26 07:47:19 volumio volumio[1365]: info: ControllerMpd::getState Mar 26 07:47:19 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Mar 26 07:47:19 volumio volumio[1365]: info: sendMpdCommand status took 1 milliseconds Mar 26 07:47:19 volumio volumio[1365]: verbose: ControllerMpd::parseState Mar 26 07:47:19 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 07:47:19 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 26 07:47:19 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Mar 26 07:47:19 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:47:19 volumio volumio[1365]: info: [programRefresh] queue updated Mar 26 07:47:19 volumio volumio[1365]: info: [programRefresh] pushed: status=play program=출발 FM과 함께 Mar 26 07:47:19 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Mar 26 07:47:19 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:47:19 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30794,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"196 Kbps","isStreaming":false,"title":"KBS ClassicFM","artist":"출발 FM과 함께","album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQ2NTExODl9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=RGnOaVVT4XABkyCbC1aXgm-rekafNs4uo6XZaPkRQqvpbtdiJcYrIM-muR1M2aagCr4a9s9mU2bszqP8TaTq8Uz99fnI7dCJUw6IdN-BEnOzKRnxHrbgbPcK0hNFSrWkWW282~IcuUqmmtadU1GzGj5tiq75SWX0gckJE7g7AL8qokXmiDk4NMDeWoOgcBZdaXubQ5gWTaB1OvW6r7Ugmk0jfGoIvN94i7m9-L8O1vt2Elnl4w8ueM7EFTZSbtBXelFlEckwRd4t1-7jOe6MPVkqijkhoEnRhucJ5nly5MM9jtAM0ZK7C~5bPoAZiBfSEqSOTiSd0kPryI6Q8OscTQ__","trackType":"","service":"carrot_radio","albumart":"https://padmin.static.kbs.co.kr/live/2021/5/28/1622175310040_252323.jpg"} Mar 26 07:47:19 volumio volumio[1365]: verbose: CURRENT POSITION 7 Mar 26 07:47:19 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Mar 26 07:47:19 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Mar 26 07:47:19 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Mar 26 07:47:19 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:47:19 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:47:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 07:47:19 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:47:19 volumio volumio[1365]: info: CoreStateMachine::pushState Mar 26 07:47:19 volumio volumio[1365]: info: CorePlayQueue::getTrack 7 Mar 26 07:47:19 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Mar 26 07:47:19 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=carrot_radio volatile=false Mar 26 07:47:19 volumio volumio[1365]: info: peppy_screensaver: pushState - status=play service=carrot_radio volatile=false Mar 26 07:47:39 volumio volumio[1365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 07:47:39 volumio volumio[1365]: Error: Unable to resolve or reject the same promise twice Mar 26 07:47:39 volumio volumio[1365]: at Promise.reject (/data/plugins/music_service/carrot_radio/node_modules/kew/kew.js:189:43) Mar 26 07:47:39 volumio volumio[1365]: at ClientRequest. (/data/plugins/music_service/carrot_radio/index.js:737:56) Mar 26 07:47:39 volumio volumio[1365]: at Object.onceWrapper (node:events:628:28) Mar 26 07:47:39 volumio volumio[1365]: at ClientRequest.emit (node:events:514:28) Mar 26 07:47:39 volumio volumio[1365]: at TLSSocket.emitRequestTimeout (node:_http_client:840:9) Mar 26 07:47:39 volumio volumio[1365]: at Object.onceWrapper (node:events:628:28) Mar 26 07:47:39 volumio volumio[1365]: at TLSSocket.emit (node:events:526:35) Mar 26 07:47:39 volumio volumio[1365]: at Socket._onTimeout (node:net:589:8) Mar 26 07:47:39 volumio volumio[1365]: at listOnTimeout (node:internal/timers:573:17) Mar 26 07:47:39 volumio volumio[1365]: at process.processTimers (node:internal/timers:514:7) Mar 26 07:47:39 volumio volumio[1365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 07:47:39 volumio sudo[5129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 07:46' Mar 26 07:47:39 volumio sudo[5129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"