Home | History | Annotate | Download | only in update_engine
      1 //
      2 // Copyright (C) 2012 The Android Open Source Project
      3 //
      4 // Licensed under the Apache License, Version 2.0 (the "License");
      5 // you may not use this file except in compliance with the License.
      6 // You may obtain a copy of the License at
      7 //
      8 //      http://www.apache.org/licenses/LICENSE-2.0
      9 //
     10 // Unless required by applicable law or agreed to in writing, software
     11 // distributed under the License is distributed on an "AS IS" BASIS,
     12 // WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
     13 // See the License for the specific language governing permissions and
     14 // limitations under the License.
     15 //
     16 
     17 #include "update_engine/update_attempter.h"
     18 
     19 #include <stdint.h>
     20 
     21 #include <algorithm>
     22 #include <memory>
     23 #include <set>
     24 #include <string>
     25 #include <utility>
     26 #include <vector>
     27 
     28 #include <base/bind.h>
     29 #include <base/files/file_util.h>
     30 #include <base/logging.h>
     31 #include <base/rand_util.h>
     32 #include <base/strings/string_util.h>
     33 #include <base/strings/stringprintf.h>
     34 #include <brillo/bind_lambda.h>
     35 #include <brillo/data_encoding.h>
     36 #include <brillo/errors/error_codes.h>
     37 #include <brillo/make_unique_ptr.h>
     38 #include <brillo/message_loops/message_loop.h>
     39 #include <policy/device_policy.h>
     40 #include <policy/libpolicy.h>
     41 #include <update_engine/dbus-constants.h>
     42 
     43 #include "update_engine/certificate_checker.h"
     44 #include "update_engine/common/boot_control_interface.h"
     45 #include "update_engine/common/clock_interface.h"
     46 #include "update_engine/common/constants.h"
     47 #include "update_engine/common/hardware_interface.h"
     48 #include "update_engine/common/platform_constants.h"
     49 #include "update_engine/common/prefs_interface.h"
     50 #include "update_engine/common/subprocess.h"
     51 #include "update_engine/common/utils.h"
     52 #include "update_engine/libcurl_http_fetcher.h"
     53 #include "update_engine/metrics.h"
     54 #include "update_engine/omaha_request_action.h"
     55 #include "update_engine/omaha_request_params.h"
     56 #include "update_engine/omaha_response_handler_action.h"
     57 #include "update_engine/p2p_manager.h"
     58 #include "update_engine/payload_consumer/download_action.h"
     59 #include "update_engine/payload_consumer/filesystem_verifier_action.h"
     60 #include "update_engine/payload_consumer/postinstall_runner_action.h"
     61 #include "update_engine/payload_state_interface.h"
     62 #include "update_engine/power_manager_interface.h"
     63 #include "update_engine/system_state.h"
     64 #include "update_engine/update_manager/policy.h"
     65 #include "update_engine/update_manager/update_manager.h"
     66 #include "update_engine/update_status_utils.h"
     67 
     68 using base::Bind;
     69 using base::Callback;
     70 using base::Time;
     71 using base::TimeDelta;
     72 using base::TimeTicks;
     73 using brillo::MessageLoop;
     74 using chromeos_update_manager::EvalStatus;
     75 using chromeos_update_manager::Policy;
     76 using chromeos_update_manager::UpdateCheckParams;
     77 using std::set;
     78 using std::shared_ptr;
     79 using std::string;
     80 using std::vector;
     81 
     82 namespace chromeos_update_engine {
     83 
     84 const int UpdateAttempter::kMaxDeltaUpdateFailures = 3;
     85 
     86 namespace {
     87 const int kMaxConsecutiveObeyProxyRequests = 20;
     88 
     89 // Minimum threshold to broadcast an status update in progress and time.
     90 const double kBroadcastThresholdProgress = 0.01;  // 1%
     91 const int kBroadcastThresholdSeconds = 10;
     92 
     93 // By default autest bypasses scattering. If we want to test scattering,
     94 // use kScheduledAUTestURLRequest. The URL used is same in both cases, but
     95 // different params are passed to CheckForUpdate().
     96 const char kAUTestURLRequest[] = "autest";
     97 const char kScheduledAUTestURLRequest[] = "autest-scheduled";
     98 }  // namespace
     99 
    100 // Turns a generic ErrorCode::kError to a generic error code specific
    101 // to |action| (e.g., ErrorCode::kFilesystemVerifierError). If |code| is
    102 // not ErrorCode::kError, or the action is not matched, returns |code|
    103 // unchanged.
    104 ErrorCode GetErrorCodeForAction(AbstractAction* action,
    105                                      ErrorCode code) {
    106   if (code != ErrorCode::kError)
    107     return code;
    108 
    109   const string type = action->Type();
    110   if (type == OmahaRequestAction::StaticType())
    111     return ErrorCode::kOmahaRequestError;
    112   if (type == OmahaResponseHandlerAction::StaticType())
    113     return ErrorCode::kOmahaResponseHandlerError;
    114   if (type == FilesystemVerifierAction::StaticType())
    115     return ErrorCode::kFilesystemVerifierError;
    116   if (type == PostinstallRunnerAction::StaticType())
    117     return ErrorCode::kPostinstallRunnerError;
    118 
    119   return code;
    120 }
    121 
    122 UpdateAttempter::UpdateAttempter(
    123     SystemState* system_state,
    124     CertificateChecker* cert_checker,
    125     org::chromium::NetworkProxyServiceInterfaceProxyInterface*
    126         network_proxy_service_proxy)
    127     : processor_(new ActionProcessor()),
    128       system_state_(system_state),
    129 #if USE_LIBCROS
    130       cert_checker_(cert_checker),
    131       chrome_proxy_resolver_(network_proxy_service_proxy) {
    132 #else
    133       cert_checker_(cert_checker) {
    134 #endif  // USE_LIBCROS
    135 }
    136 
    137 UpdateAttempter::~UpdateAttempter() {
    138   // CertificateChecker might not be initialized in unittests.
    139   if (cert_checker_)
    140     cert_checker_->SetObserver(nullptr);
    141   // Release ourselves as the ActionProcessor's delegate to prevent
    142   // re-scheduling the updates due to the processing stopped.
    143   processor_->set_delegate(nullptr);
    144 }
    145 
    146 void UpdateAttempter::Init() {
    147   // Pulling from the SystemState can only be done after construction, since
    148   // this is an aggregate of various objects (such as the UpdateAttempter),
    149   // which requires them all to be constructed prior to it being used.
    150   prefs_ = system_state_->prefs();
    151   omaha_request_params_ = system_state_->request_params();
    152 
    153   if (cert_checker_)
    154     cert_checker_->SetObserver(this);
    155 
    156   // In case of update_engine restart without a reboot we need to restore the
    157   // reboot needed state.
    158   if (GetBootTimeAtUpdate(nullptr))
    159     status_ = UpdateStatus::UPDATED_NEED_REBOOT;
    160   else
    161     status_ = UpdateStatus::IDLE;
    162 }
    163 
    164 void UpdateAttempter::ScheduleUpdates() {
    165   if (IsUpdateRunningOrScheduled())
    166     return;
    167 
    168   chromeos_update_manager::UpdateManager* const update_manager =
    169       system_state_->update_manager();
    170   CHECK(update_manager);
    171   Callback<void(EvalStatus, const UpdateCheckParams&)> callback = Bind(
    172       &UpdateAttempter::OnUpdateScheduled, base::Unretained(this));
    173   // We limit the async policy request to a reasonably short time, to avoid a
    174   // starvation due to a transient bug.
    175   update_manager->AsyncPolicyRequest(callback, &Policy::UpdateCheckAllowed);
    176   waiting_for_scheduled_check_ = true;
    177 }
    178 
    179 void UpdateAttempter::CertificateChecked(ServerToCheck server_to_check,
    180                                          CertificateCheckResult result) {
    181   metrics::ReportCertificateCheckMetrics(system_state_,
    182                                          server_to_check,
    183                                          result);
    184 }
    185 
    186 bool UpdateAttempter::CheckAndReportDailyMetrics() {
    187   int64_t stored_value;
    188   Time now = system_state_->clock()->GetWallclockTime();
    189   if (system_state_->prefs()->Exists(kPrefsDailyMetricsLastReportedAt) &&
    190       system_state_->prefs()->GetInt64(kPrefsDailyMetricsLastReportedAt,
    191                                        &stored_value)) {
    192     Time last_reported_at = Time::FromInternalValue(stored_value);
    193     TimeDelta time_reported_since = now - last_reported_at;
    194     if (time_reported_since.InSeconds() < 0) {
    195       LOG(WARNING) << "Last reported daily metrics "
    196                    << utils::FormatTimeDelta(time_reported_since) << " ago "
    197                    << "which is negative. Either the system clock is wrong or "
    198                    << "the kPrefsDailyMetricsLastReportedAt state variable "
    199                    << "is wrong.";
    200       // In this case, report daily metrics to reset.
    201     } else {
    202       if (time_reported_since.InSeconds() < 24*60*60) {
    203         LOG(INFO) << "Last reported daily metrics "
    204                   << utils::FormatTimeDelta(time_reported_since) << " ago.";
    205         return false;
    206       }
    207       LOG(INFO) << "Last reported daily metrics "
    208                 << utils::FormatTimeDelta(time_reported_since) << " ago, "
    209                 << "which is more than 24 hours ago.";
    210     }
    211   }
    212 
    213   LOG(INFO) << "Reporting daily metrics.";
    214   system_state_->prefs()->SetInt64(kPrefsDailyMetricsLastReportedAt,
    215                                    now.ToInternalValue());
    216 
    217   ReportOSAge();
    218 
    219   return true;
    220 }
    221 
    222 void UpdateAttempter::ReportOSAge() {
    223   struct stat sb;
    224 
    225   if (system_state_ == nullptr)
    226     return;
    227 
    228   if (stat("/etc/lsb-release", &sb) != 0) {
    229     PLOG(ERROR) << "Error getting file status for /etc/lsb-release "
    230                 << "(Note: this may happen in some unit tests)";
    231     return;
    232   }
    233 
    234   Time lsb_release_timestamp = utils::TimeFromStructTimespec(&sb.st_ctim);
    235   Time now = system_state_->clock()->GetWallclockTime();
    236   TimeDelta age = now - lsb_release_timestamp;
    237   if (age.InSeconds() < 0) {
    238     LOG(ERROR) << "The OS age (" << utils::FormatTimeDelta(age)
    239                << ") is negative. Maybe the clock is wrong? "
    240                << "(Note: this may happen in some unit tests.)";
    241     return;
    242   }
    243 
    244   metrics::ReportDailyMetrics(system_state_, age);
    245 }
    246 
    247 void UpdateAttempter::Update(const string& app_version,
    248                              const string& omaha_url,
    249                              const string& target_channel,
    250                              const string& target_version_prefix,
    251                              bool obey_proxies,
    252                              bool interactive) {
    253   // This is normally called frequently enough so it's appropriate to use as a
    254   // hook for reporting daily metrics.
    255   // TODO(garnold) This should be hooked to a separate (reliable and consistent)
    256   // timeout event.
    257   CheckAndReportDailyMetrics();
    258 
    259   // Notify of the new update attempt, clearing prior interactive requests.
    260   if (forced_update_pending_callback_.get())
    261     forced_update_pending_callback_->Run(false, false);
    262 
    263   fake_update_success_ = false;
    264   if (status_ == UpdateStatus::UPDATED_NEED_REBOOT) {
    265     // Although we have applied an update, we still want to ping Omaha
    266     // to ensure the number of active statistics is accurate.
    267     //
    268     // Also convey to the UpdateEngine.Check.Result metric that we're
    269     // not performing an update check because of this.
    270     LOG(INFO) << "Not updating b/c we already updated and we're waiting for "
    271               << "reboot, we'll ping Omaha instead";
    272     metrics::ReportUpdateCheckMetrics(system_state_,
    273                                       metrics::CheckResult::kRebootPending,
    274                                       metrics::CheckReaction::kUnset,
    275                                       metrics::DownloadErrorCode::kUnset);
    276     PingOmaha();
    277     return;
    278   }
    279   if (status_ != UpdateStatus::IDLE) {
    280     // Update in progress. Do nothing
    281     return;
    282   }
    283 
    284   if (!CalculateUpdateParams(app_version,
    285                              omaha_url,
    286                              target_channel,
    287                              target_version_prefix,
    288                              obey_proxies,
    289                              interactive)) {
    290     return;
    291   }
    292 
    293   BuildUpdateActions(interactive);
    294 
    295   SetStatusAndNotify(UpdateStatus::CHECKING_FOR_UPDATE);
    296 
    297   // Update the last check time here; it may be re-updated when an Omaha
    298   // response is received, but this will prevent us from repeatedly scheduling
    299   // checks in the case where a response is not received.
    300   UpdateLastCheckedTime();
    301 
    302   // Just in case we didn't update boot flags yet, make sure they're updated
    303   // before any update processing starts.
    304   start_action_processor_ = true;
    305   UpdateBootFlags();
    306 }
    307 
    308 void UpdateAttempter::RefreshDevicePolicy() {
    309   // Lazy initialize the policy provider, or reload the latest policy data.
    310   if (!policy_provider_.get())
    311     policy_provider_.reset(new policy::PolicyProvider());
    312   policy_provider_->Reload();
    313 
    314   const policy::DevicePolicy* device_policy = nullptr;
    315   if (policy_provider_->device_policy_is_loaded())
    316     device_policy = &policy_provider_->GetDevicePolicy();
    317 
    318   if (device_policy)
    319     LOG(INFO) << "Device policies/settings present";
    320   else
    321     LOG(INFO) << "No device policies/settings present.";
    322 
    323   system_state_->set_device_policy(device_policy);
    324   system_state_->p2p_manager()->SetDevicePolicy(device_policy);
    325 }
    326 
    327 void UpdateAttempter::CalculateP2PParams(bool interactive) {
    328   bool use_p2p_for_downloading = false;
    329   bool use_p2p_for_sharing = false;
    330 
    331   // Never use p2p for downloading in interactive checks unless the
    332   // developer has opted in for it via a marker file.
    333   //
    334   // (Why would a developer want to opt in? If he's working on the
    335   // update_engine or p2p codebases so he can actually test his
    336   // code.).
    337 
    338   if (system_state_ != nullptr) {
    339     if (!system_state_->p2p_manager()->IsP2PEnabled()) {
    340       LOG(INFO) << "p2p is not enabled - disallowing p2p for both"
    341                 << " downloading and sharing.";
    342     } else {
    343       // Allow p2p for sharing, even in interactive checks.
    344       use_p2p_for_sharing = true;
    345       if (!interactive) {
    346         LOG(INFO) << "Non-interactive check - allowing p2p for downloading";
    347         use_p2p_for_downloading = true;
    348       } else {
    349         LOG(INFO) << "Forcibly disabling use of p2p for downloading "
    350                   << "since this update attempt is interactive.";
    351       }
    352     }
    353   }
    354 
    355   PayloadStateInterface* const payload_state = system_state_->payload_state();
    356   payload_state->SetUsingP2PForDownloading(use_p2p_for_downloading);
    357   payload_state->SetUsingP2PForSharing(use_p2p_for_sharing);
    358 }
    359 
    360 bool UpdateAttempter::CalculateUpdateParams(const string& app_version,
    361                                             const string& omaha_url,
    362                                             const string& target_channel,
    363                                             const string& target_version_prefix,
    364                                             bool obey_proxies,
    365                                             bool interactive) {
    366   http_response_code_ = 0;
    367   PayloadStateInterface* const payload_state = system_state_->payload_state();
    368 
    369   // Refresh the policy before computing all the update parameters.
    370   RefreshDevicePolicy();
    371 
    372   // Update the target version prefix.
    373   omaha_request_params_->set_target_version_prefix(target_version_prefix);
    374 
    375   CalculateScatteringParams(interactive);
    376 
    377   CalculateP2PParams(interactive);
    378   if (payload_state->GetUsingP2PForDownloading() ||
    379       payload_state->GetUsingP2PForSharing()) {
    380     // OK, p2p is to be used - start it and perform housekeeping.
    381     if (!StartP2PAndPerformHousekeeping()) {
    382       // If this fails, disable p2p for this attempt
    383       LOG(INFO) << "Forcibly disabling use of p2p since starting p2p or "
    384                 << "performing housekeeping failed.";
    385       payload_state->SetUsingP2PForDownloading(false);
    386       payload_state->SetUsingP2PForSharing(false);
    387     }
    388   }
    389 
    390   if (!omaha_request_params_->Init(app_version,
    391                                    omaha_url,
    392                                    interactive)) {
    393     LOG(ERROR) << "Unable to initialize Omaha request params.";
    394     return false;
    395   }
    396 
    397   // Set the target channel, if one was provided.
    398   if (target_channel.empty()) {
    399     LOG(INFO) << "No target channel mandated by policy.";
    400   } else {
    401     LOG(INFO) << "Setting target channel as mandated: " << target_channel;
    402     // Pass in false for powerwash_allowed until we add it to the policy
    403     // protobuf.
    404     string error_message;
    405     if (!omaha_request_params_->SetTargetChannel(target_channel, false,
    406                                                  &error_message)) {
    407       LOG(ERROR) << "Setting the channel failed: " << error_message;
    408     }
    409 
    410     // Since this is the beginning of a new attempt, update the download
    411     // channel. The download channel won't be updated until the next attempt,
    412     // even if target channel changes meanwhile, so that how we'll know if we
    413     // should cancel the current download attempt if there's such a change in
    414     // target channel.
    415     omaha_request_params_->UpdateDownloadChannel();
    416   }
    417 
    418   LOG(INFO) << "target_version_prefix = "
    419             << omaha_request_params_->target_version_prefix()
    420             << ", scatter_factor_in_seconds = "
    421             << utils::FormatSecs(scatter_factor_.InSeconds());
    422 
    423   LOG(INFO) << "Wall Clock Based Wait Enabled = "
    424             << omaha_request_params_->wall_clock_based_wait_enabled()
    425             << ", Update Check Count Wait Enabled = "
    426             << omaha_request_params_->update_check_count_wait_enabled()
    427             << ", Waiting Period = " << utils::FormatSecs(
    428                omaha_request_params_->waiting_period().InSeconds());
    429 
    430   LOG(INFO) << "Use p2p For Downloading = "
    431             << payload_state->GetUsingP2PForDownloading()
    432             << ", Use p2p For Sharing = "
    433             << payload_state->GetUsingP2PForSharing();
    434 
    435   obeying_proxies_ = true;
    436   if (obey_proxies || proxy_manual_checks_ == 0) {
    437     LOG(INFO) << "forced to obey proxies";
    438     // If forced to obey proxies, every 20th request will not use proxies
    439     proxy_manual_checks_++;
    440     LOG(INFO) << "proxy manual checks: " << proxy_manual_checks_;
    441     if (proxy_manual_checks_ >= kMaxConsecutiveObeyProxyRequests) {
    442       proxy_manual_checks_ = 0;
    443       obeying_proxies_ = false;
    444     }
    445   } else if (base::RandInt(0, 4) == 0) {
    446     obeying_proxies_ = false;
    447   }
    448   LOG_IF(INFO, !obeying_proxies_) << "To help ensure updates work, this update "
    449       "check we are ignoring the proxy settings and using "
    450       "direct connections.";
    451 
    452   DisableDeltaUpdateIfNeeded();
    453   return true;
    454 }
    455 
    456 void UpdateAttempter::CalculateScatteringParams(bool interactive) {
    457   // Take a copy of the old scatter value before we update it, as
    458   // we need to update the waiting period if this value changes.
    459   TimeDelta old_scatter_factor = scatter_factor_;
    460   const policy::DevicePolicy* device_policy = system_state_->device_policy();
    461   if (device_policy) {
    462     int64_t new_scatter_factor_in_secs = 0;
    463     device_policy->GetScatterFactorInSeconds(&new_scatter_factor_in_secs);
    464     if (new_scatter_factor_in_secs < 0)  // sanitize input, just in case.
    465       new_scatter_factor_in_secs  = 0;
    466     scatter_factor_ = TimeDelta::FromSeconds(new_scatter_factor_in_secs);
    467   }
    468 
    469   bool is_scatter_enabled = false;
    470   if (scatter_factor_.InSeconds() == 0) {
    471     LOG(INFO) << "Scattering disabled since scatter factor is set to 0";
    472   } else if (interactive) {
    473     LOG(INFO) << "Scattering disabled as this is an interactive update check";
    474   } else if (system_state_->hardware()->IsOOBEEnabled() &&
    475              !system_state_->hardware()->IsOOBEComplete(nullptr)) {
    476     LOG(INFO) << "Scattering disabled since OOBE is enabled but not complete "
    477                  "yet";
    478   } else {
    479     is_scatter_enabled = true;
    480     LOG(INFO) << "Scattering is enabled";
    481   }
    482 
    483   if (is_scatter_enabled) {
    484     // This means the scattering policy is turned on.
    485     // Now check if we need to update the waiting period. The two cases
    486     // in which we'd need to update the waiting period are:
    487     // 1. First time in process or a scheduled check after a user-initiated one.
    488     //    (omaha_request_params_->waiting_period will be zero in this case).
    489     // 2. Admin has changed the scattering policy value.
    490     //    (new scattering value will be different from old one in this case).
    491     int64_t wait_period_in_secs = 0;
    492     if (omaha_request_params_->waiting_period().InSeconds() == 0) {
    493       // First case. Check if we have a suitable value to set for
    494       // the waiting period.
    495       if (prefs_->GetInt64(kPrefsWallClockWaitPeriod, &wait_period_in_secs) &&
    496           wait_period_in_secs > 0 &&
    497           wait_period_in_secs <= scatter_factor_.InSeconds()) {
    498         // This means:
    499         // 1. There's a persisted value for the waiting period available.
    500         // 2. And that persisted value is still valid.
    501         // So, in this case, we should reuse the persisted value instead of
    502         // generating a new random value to improve the chances of a good
    503         // distribution for scattering.
    504         omaha_request_params_->set_waiting_period(
    505           TimeDelta::FromSeconds(wait_period_in_secs));
    506         LOG(INFO) << "Using persisted wall-clock waiting period: " <<
    507             utils::FormatSecs(
    508                 omaha_request_params_->waiting_period().InSeconds());
    509       } else {
    510         // This means there's no persisted value for the waiting period
    511         // available or its value is invalid given the new scatter_factor value.
    512         // So, we should go ahead and regenerate a new value for the
    513         // waiting period.
    514         LOG(INFO) << "Persisted value not present or not valid ("
    515                   << utils::FormatSecs(wait_period_in_secs)
    516                   << ") for wall-clock waiting period.";
    517         GenerateNewWaitingPeriod();
    518       }
    519     } else if (scatter_factor_ != old_scatter_factor) {
    520       // This means there's already a waiting period value, but we detected
    521       // a change in the scattering policy value. So, we should regenerate the
    522       // waiting period to make sure it's within the bounds of the new scatter
    523       // factor value.
    524       GenerateNewWaitingPeriod();
    525     } else {
    526       // Neither the first time scattering is enabled nor the scattering value
    527       // changed. Nothing to do.
    528       LOG(INFO) << "Keeping current wall-clock waiting period: " <<
    529           utils::FormatSecs(
    530               omaha_request_params_->waiting_period().InSeconds());
    531     }
    532 
    533     // The invariant at this point is that omaha_request_params_->waiting_period
    534     // is non-zero no matter which path we took above.
    535     LOG_IF(ERROR, omaha_request_params_->waiting_period().InSeconds() == 0)
    536         << "Waiting Period should NOT be zero at this point!!!";
    537 
    538     // Since scattering is enabled, wall clock based wait will always be
    539     // enabled.
    540     omaha_request_params_->set_wall_clock_based_wait_enabled(true);
    541 
    542     // If we don't have any issues in accessing the file system to update
    543     // the update check count value, we'll turn that on as well.
    544     bool decrement_succeeded = DecrementUpdateCheckCount();
    545     omaha_request_params_->set_update_check_count_wait_enabled(
    546       decrement_succeeded);
    547   } else {
    548     // This means the scattering feature is turned off or disabled for
    549     // this particular update check. Make sure to disable
    550     // all the knobs and artifacts so that we don't invoke any scattering
    551     // related code.
    552     omaha_request_params_->set_wall_clock_based_wait_enabled(false);
    553     omaha_request_params_->set_update_check_count_wait_enabled(false);
    554     omaha_request_params_->set_waiting_period(TimeDelta::FromSeconds(0));
    555     prefs_->Delete(kPrefsWallClockWaitPeriod);
    556     prefs_->Delete(kPrefsUpdateCheckCount);
    557     // Don't delete the UpdateFirstSeenAt file as we don't want manual checks
    558     // that result in no-updates (e.g. due to server side throttling) to
    559     // cause update starvation by having the client generate a new
    560     // UpdateFirstSeenAt for each scheduled check that follows a manual check.
    561   }
    562 }
    563 
    564 void UpdateAttempter::GenerateNewWaitingPeriod() {
    565   omaha_request_params_->set_waiting_period(TimeDelta::FromSeconds(
    566       base::RandInt(1, scatter_factor_.InSeconds())));
    567 
    568   LOG(INFO) << "Generated new wall-clock waiting period: " << utils::FormatSecs(
    569                 omaha_request_params_->waiting_period().InSeconds());
    570 
    571   // Do a best-effort to persist this in all cases. Even if the persistence
    572   // fails, we'll still be able to scatter based on our in-memory value.
    573   // The persistence only helps in ensuring a good overall distribution
    574   // across multiple devices if they tend to reboot too often.
    575   system_state_->payload_state()->SetScatteringWaitPeriod(
    576       omaha_request_params_->waiting_period());
    577 }
    578 
    579 void UpdateAttempter::BuildPostInstallActions(
    580     InstallPlanAction* previous_action) {
    581   shared_ptr<PostinstallRunnerAction> postinstall_runner_action(
    582       new PostinstallRunnerAction(system_state_->boot_control(),
    583                                   system_state_->hardware()));
    584   postinstall_runner_action->set_delegate(this);
    585   actions_.push_back(shared_ptr<AbstractAction>(postinstall_runner_action));
    586   BondActions(previous_action,
    587               postinstall_runner_action.get());
    588 }
    589 
    590 void UpdateAttempter::BuildUpdateActions(bool interactive) {
    591   CHECK(!processor_->IsRunning());
    592   processor_->set_delegate(this);
    593 
    594   // Actions:
    595   std::unique_ptr<LibcurlHttpFetcher> update_check_fetcher(
    596       new LibcurlHttpFetcher(GetProxyResolver(), system_state_->hardware()));
    597   update_check_fetcher->set_server_to_check(ServerToCheck::kUpdate);
    598   // Try harder to connect to the network, esp when not interactive.
    599   // See comment in libcurl_http_fetcher.cc.
    600   update_check_fetcher->set_no_network_max_retries(interactive ? 1 : 3);
    601   shared_ptr<OmahaRequestAction> update_check_action(
    602       new OmahaRequestAction(system_state_,
    603                              nullptr,
    604                              std::move(update_check_fetcher),
    605                              false));
    606   shared_ptr<OmahaResponseHandlerAction> response_handler_action(
    607       new OmahaResponseHandlerAction(system_state_));
    608 
    609   shared_ptr<OmahaRequestAction> download_started_action(
    610       new OmahaRequestAction(system_state_,
    611                              new OmahaEvent(
    612                                  OmahaEvent::kTypeUpdateDownloadStarted),
    613                              brillo::make_unique_ptr(new LibcurlHttpFetcher(
    614                                  GetProxyResolver(),
    615                                  system_state_->hardware())),
    616                              false));
    617 
    618   LibcurlHttpFetcher* download_fetcher =
    619       new LibcurlHttpFetcher(GetProxyResolver(), system_state_->hardware());
    620   download_fetcher->set_server_to_check(ServerToCheck::kDownload);
    621   shared_ptr<DownloadAction> download_action(
    622       new DownloadAction(prefs_,
    623                          system_state_->boot_control(),
    624                          system_state_->hardware(),
    625                          system_state_,
    626                          download_fetcher));  // passes ownership
    627   shared_ptr<OmahaRequestAction> download_finished_action(
    628       new OmahaRequestAction(
    629           system_state_,
    630           new OmahaEvent(OmahaEvent::kTypeUpdateDownloadFinished),
    631           brillo::make_unique_ptr(
    632               new LibcurlHttpFetcher(GetProxyResolver(),
    633                                      system_state_->hardware())),
    634           false));
    635   shared_ptr<FilesystemVerifierAction> filesystem_verifier_action(
    636       new FilesystemVerifierAction());
    637   shared_ptr<OmahaRequestAction> update_complete_action(
    638       new OmahaRequestAction(
    639           system_state_,
    640           new OmahaEvent(OmahaEvent::kTypeUpdateComplete),
    641           brillo::make_unique_ptr(
    642               new LibcurlHttpFetcher(GetProxyResolver(),
    643                                      system_state_->hardware())),
    644           false));
    645 
    646   download_action->set_delegate(this);
    647   response_handler_action_ = response_handler_action;
    648   download_action_ = download_action;
    649 
    650   actions_.push_back(shared_ptr<AbstractAction>(update_check_action));
    651   actions_.push_back(shared_ptr<AbstractAction>(response_handler_action));
    652   actions_.push_back(shared_ptr<AbstractAction>(download_started_action));
    653   actions_.push_back(shared_ptr<AbstractAction>(download_action));
    654   actions_.push_back(shared_ptr<AbstractAction>(download_finished_action));
    655   actions_.push_back(shared_ptr<AbstractAction>(filesystem_verifier_action));
    656 
    657   // Bond them together. We have to use the leaf-types when calling
    658   // BondActions().
    659   BondActions(update_check_action.get(),
    660               response_handler_action.get());
    661   BondActions(response_handler_action.get(),
    662               download_action.get());
    663   BondActions(download_action.get(),
    664               filesystem_verifier_action.get());
    665   BuildPostInstallActions(filesystem_verifier_action.get());
    666 
    667   actions_.push_back(shared_ptr<AbstractAction>(update_complete_action));
    668 
    669   // Enqueue the actions
    670   for (const shared_ptr<AbstractAction>& action : actions_) {
    671     processor_->EnqueueAction(action.get());
    672   }
    673 }
    674 
    675 bool UpdateAttempter::Rollback(bool powerwash) {
    676   if (!CanRollback()) {
    677     return false;
    678   }
    679 
    680   // Extra check for enterprise-enrolled devices since they don't support
    681   // powerwash.
    682   if (powerwash) {
    683     // Enterprise-enrolled devices have an empty owner in their device policy.
    684     string owner;
    685     RefreshDevicePolicy();
    686     const policy::DevicePolicy* device_policy = system_state_->device_policy();
    687     if (device_policy && (!device_policy->GetOwner(&owner) || owner.empty())) {
    688       LOG(ERROR) << "Enterprise device detected. "
    689                  << "Cannot perform a powerwash for enterprise devices.";
    690       return false;
    691     }
    692   }
    693 
    694   processor_->set_delegate(this);
    695 
    696   // Initialize the default request params.
    697   if (!omaha_request_params_->Init("", "", true)) {
    698     LOG(ERROR) << "Unable to initialize Omaha request params.";
    699     return false;
    700   }
    701 
    702   LOG(INFO) << "Setting rollback options.";
    703   InstallPlan install_plan;
    704 
    705   install_plan.target_slot = GetRollbackSlot();
    706   install_plan.source_slot = system_state_->boot_control()->GetCurrentSlot();
    707 
    708   TEST_AND_RETURN_FALSE(
    709       install_plan.LoadPartitionsFromSlots(system_state_->boot_control()));
    710   install_plan.powerwash_required = powerwash;
    711 
    712   LOG(INFO) << "Using this install plan:";
    713   install_plan.Dump();
    714 
    715   shared_ptr<InstallPlanAction> install_plan_action(
    716       new InstallPlanAction(install_plan));
    717   actions_.push_back(shared_ptr<AbstractAction>(install_plan_action));
    718 
    719   BuildPostInstallActions(install_plan_action.get());
    720 
    721   // Enqueue the actions
    722   for (const shared_ptr<AbstractAction>& action : actions_) {
    723     processor_->EnqueueAction(action.get());
    724   }
    725 
    726   // Update the payload state for Rollback.
    727   system_state_->payload_state()->Rollback();
    728 
    729   SetStatusAndNotify(UpdateStatus::ATTEMPTING_ROLLBACK);
    730 
    731   // Just in case we didn't update boot flags yet, make sure they're updated
    732   // before any update processing starts. This also schedules the start of the
    733   // actions we just posted.
    734   start_action_processor_ = true;
    735   UpdateBootFlags();
    736   return true;
    737 }
    738 
    739 bool UpdateAttempter::CanRollback() const {
    740   // We can only rollback if the update_engine isn't busy and we have a valid
    741   // rollback partition.
    742   return (status_ == UpdateStatus::IDLE &&
    743           GetRollbackSlot() != BootControlInterface::kInvalidSlot);
    744 }
    745 
    746 BootControlInterface::Slot UpdateAttempter::GetRollbackSlot() const {
    747   LOG(INFO) << "UpdateAttempter::GetRollbackSlot";
    748   const unsigned int num_slots = system_state_->boot_control()->GetNumSlots();
    749   const BootControlInterface::Slot current_slot =
    750       system_state_->boot_control()->GetCurrentSlot();
    751 
    752   LOG(INFO) << "  Installed slots: " << num_slots;
    753   LOG(INFO) << "  Booted from slot: "
    754             << BootControlInterface::SlotName(current_slot);
    755 
    756   if (current_slot == BootControlInterface::kInvalidSlot || num_slots < 2) {
    757     LOG(INFO) << "Device is not updateable.";
    758     return BootControlInterface::kInvalidSlot;
    759   }
    760 
    761   vector<BootControlInterface::Slot> bootable_slots;
    762   for (BootControlInterface::Slot slot = 0; slot < num_slots; slot++) {
    763     if (slot != current_slot &&
    764         system_state_->boot_control()->IsSlotBootable(slot)) {
    765       LOG(INFO) << "Found bootable slot "
    766                 << BootControlInterface::SlotName(slot);
    767       return slot;
    768     }
    769   }
    770   LOG(INFO) << "No other bootable slot found.";
    771   return BootControlInterface::kInvalidSlot;
    772 }
    773 
    774 void UpdateAttempter::CheckForUpdate(const string& app_version,
    775                                      const string& omaha_url,
    776                                      bool interactive) {
    777   LOG(INFO) << "Forced update check requested.";
    778   forced_app_version_.clear();
    779   forced_omaha_url_.clear();
    780 
    781   // Certain conditions must be met to allow setting custom version and update
    782   // server URLs. However, kScheduledAUTestURLRequest and kAUTestURLRequest are
    783   // always allowed regardless of device state.
    784   if (IsAnyUpdateSourceAllowed()) {
    785     forced_app_version_ = app_version;
    786     forced_omaha_url_ = omaha_url;
    787   }
    788   if (omaha_url == kScheduledAUTestURLRequest) {
    789     forced_omaha_url_ = constants::kOmahaDefaultAUTestURL;
    790     // Pretend that it's not user-initiated even though it is,
    791     // so as to test scattering logic, etc. which get kicked off
    792     // only in scheduled update checks.
    793     interactive = false;
    794   } else if (omaha_url == kAUTestURLRequest) {
    795     forced_omaha_url_ = constants::kOmahaDefaultAUTestURL;
    796   }
    797 
    798   if (forced_update_pending_callback_.get()) {
    799     // Make sure that a scheduling request is made prior to calling the forced
    800     // update pending callback.
    801     ScheduleUpdates();
    802     forced_update_pending_callback_->Run(true, interactive);
    803   }
    804 }
    805 
    806 bool UpdateAttempter::RebootIfNeeded() {
    807   if (status_ != UpdateStatus::UPDATED_NEED_REBOOT) {
    808     LOG(INFO) << "Reboot requested, but status is "
    809               << UpdateStatusToString(status_) << ", so not rebooting.";
    810     return false;
    811   }
    812 
    813   if (system_state_->power_manager()->RequestReboot())
    814     return true;
    815 
    816   return RebootDirectly();
    817 }
    818 
    819 void UpdateAttempter::WriteUpdateCompletedMarker() {
    820   string boot_id;
    821   if (!utils::GetBootId(&boot_id))
    822     return;
    823   prefs_->SetString(kPrefsUpdateCompletedOnBootId, boot_id);
    824 
    825   int64_t value = system_state_->clock()->GetBootTime().ToInternalValue();
    826   prefs_->SetInt64(kPrefsUpdateCompletedBootTime, value);
    827 }
    828 
    829 bool UpdateAttempter::RebootDirectly() {
    830   vector<string> command;
    831   command.push_back("/sbin/shutdown");
    832   command.push_back("-r");
    833   command.push_back("now");
    834   LOG(INFO) << "Running \"" << base::JoinString(command, " ") << "\"";
    835   int rc = 0;
    836   Subprocess::SynchronousExec(command, &rc, nullptr);
    837   return rc == 0;
    838 }
    839 
    840 void UpdateAttempter::OnUpdateScheduled(EvalStatus status,
    841                                         const UpdateCheckParams& params) {
    842   waiting_for_scheduled_check_ = false;
    843 
    844   if (status == EvalStatus::kSucceeded) {
    845     if (!params.updates_enabled) {
    846       LOG(WARNING) << "Updates permanently disabled.";
    847       // Signal disabled status, then switch right back to idle. This is
    848       // necessary for ensuring that observers waiting for a signal change will
    849       // actually notice one on subsequent calls. Note that we don't need to
    850       // re-schedule a check in this case as updates are permanently disabled;
    851       // further (forced) checks may still initiate a scheduling call.
    852       SetStatusAndNotify(UpdateStatus::DISABLED);
    853       SetStatusAndNotify(UpdateStatus::IDLE);
    854       return;
    855     }
    856 
    857     LOG(INFO) << "Running "
    858               << (params.is_interactive ? "interactive" : "periodic")
    859               << " update.";
    860 
    861     Update(forced_app_version_, forced_omaha_url_, params.target_channel,
    862            params.target_version_prefix, false, params.is_interactive);
    863     // Always clear the forced app_version and omaha_url after an update attempt
    864     // so the next update uses the defaults.
    865     forced_app_version_.clear();
    866     forced_omaha_url_.clear();
    867   } else {
    868     LOG(WARNING)
    869         << "Update check scheduling failed (possibly timed out); retrying.";
    870     ScheduleUpdates();
    871   }
    872 
    873   // This check ensures that future update checks will be or are already
    874   // scheduled. The check should never fail. A check failure means that there's
    875   // a bug that will most likely prevent further automatic update checks. It
    876   // seems better to crash in such cases and restart the update_engine daemon
    877   // into, hopefully, a known good state.
    878   CHECK(IsUpdateRunningOrScheduled());
    879 }
    880 
    881 void UpdateAttempter::UpdateLastCheckedTime() {
    882   last_checked_time_ = system_state_->clock()->GetWallclockTime().ToTimeT();
    883 }
    884 
    885 // Delegate methods:
    886 void UpdateAttempter::ProcessingDone(const ActionProcessor* processor,
    887                                      ErrorCode code) {
    888   LOG(INFO) << "Processing Done.";
    889   actions_.clear();
    890 
    891   // Reset cpu shares back to normal.
    892   cpu_limiter_.StopLimiter();
    893 
    894   if (status_ == UpdateStatus::REPORTING_ERROR_EVENT) {
    895     LOG(INFO) << "Error event sent.";
    896 
    897     // Inform scheduler of new status;
    898     SetStatusAndNotify(UpdateStatus::IDLE);
    899     ScheduleUpdates();
    900 
    901     if (!fake_update_success_) {
    902       return;
    903     }
    904     LOG(INFO) << "Booted from FW B and tried to install new firmware, "
    905         "so requesting reboot from user.";
    906   }
    907 
    908   if (code == ErrorCode::kSuccess) {
    909     WriteUpdateCompletedMarker();
    910     prefs_->SetInt64(kPrefsDeltaUpdateFailures, 0);
    911     prefs_->SetString(kPrefsPreviousVersion,
    912                       omaha_request_params_->app_version());
    913     DeltaPerformer::ResetUpdateProgress(prefs_, false);
    914 
    915     system_state_->payload_state()->UpdateSucceeded();
    916 
    917     // Since we're done with scattering fully at this point, this is the
    918     // safest point delete the state files, as we're sure that the status is
    919     // set to reboot (which means no more updates will be applied until reboot)
    920     // This deletion is required for correctness as we want the next update
    921     // check to re-create a new random number for the update check count.
    922     // Similarly, we also delete the wall-clock-wait period that was persisted
    923     // so that we start with a new random value for the next update check
    924     // after reboot so that the same device is not favored or punished in any
    925     // way.
    926     prefs_->Delete(kPrefsUpdateCheckCount);
    927     system_state_->payload_state()->SetScatteringWaitPeriod(TimeDelta());
    928     prefs_->Delete(kPrefsUpdateFirstSeenAt);
    929 
    930     SetStatusAndNotify(UpdateStatus::UPDATED_NEED_REBOOT);
    931     ScheduleUpdates();
    932     LOG(INFO) << "Update successfully applied, waiting to reboot.";
    933 
    934     // This pointer is null during rollback operations, and the stats
    935     // don't make much sense then anyway.
    936     if (response_handler_action_) {
    937       const InstallPlan& install_plan =
    938           response_handler_action_->install_plan();
    939 
    940       // Generate an unique payload identifier.
    941       string target_version_uid;
    942       for (const auto& payload : install_plan.payloads) {
    943         target_version_uid +=
    944             brillo::data_encoding::Base64Encode(payload.hash) + ":" +
    945             payload.metadata_signature + ":";
    946       }
    947 
    948       // Expect to reboot into the new version to send the proper metric during
    949       // next boot.
    950       system_state_->payload_state()->ExpectRebootInNewVersion(
    951           target_version_uid);
    952     } else {
    953       // If we just finished a rollback, then we expect to have no Omaha
    954       // response. Otherwise, it's an error.
    955       if (system_state_->payload_state()->GetRollbackVersion().empty()) {
    956         LOG(ERROR) << "Can't send metrics because expected "
    957             "response_handler_action_ missing.";
    958       }
    959     }
    960     return;
    961   }
    962 
    963   if (ScheduleErrorEventAction()) {
    964     return;
    965   }
    966   LOG(INFO) << "No update.";
    967   SetStatusAndNotify(UpdateStatus::IDLE);
    968   ScheduleUpdates();
    969 }
    970 
    971 void UpdateAttempter::ProcessingStopped(const ActionProcessor* processor) {
    972   // Reset cpu shares back to normal.
    973   cpu_limiter_.StopLimiter();
    974   download_progress_ = 0.0;
    975   SetStatusAndNotify(UpdateStatus::IDLE);
    976   ScheduleUpdates();
    977   actions_.clear();
    978   error_event_.reset(nullptr);
    979 }
    980 
    981 // Called whenever an action has finished processing, either successfully
    982 // or otherwise.
    983 void UpdateAttempter::ActionCompleted(ActionProcessor* processor,
    984                                       AbstractAction* action,
    985                                       ErrorCode code) {
    986   // Reset download progress regardless of whether or not the download
    987   // action succeeded. Also, get the response code from HTTP request
    988   // actions (update download as well as the initial update check
    989   // actions).
    990   const string type = action->Type();
    991   if (type == DownloadAction::StaticType()) {
    992     download_progress_ = 0.0;
    993     DownloadAction* download_action = static_cast<DownloadAction*>(action);
    994     http_response_code_ = download_action->GetHTTPResponseCode();
    995   } else if (type == OmahaRequestAction::StaticType()) {
    996     OmahaRequestAction* omaha_request_action =
    997         static_cast<OmahaRequestAction*>(action);
    998     // If the request is not an event, then it's the update-check.
    999     if (!omaha_request_action->IsEvent()) {
   1000       http_response_code_ = omaha_request_action->GetHTTPResponseCode();
   1001 
   1002       // Record the number of consecutive failed update checks.
   1003       if (http_response_code_ == kHttpResponseInternalServerError ||
   1004           http_response_code_ == kHttpResponseServiceUnavailable) {
   1005         consecutive_failed_update_checks_++;
   1006       } else {
   1007         consecutive_failed_update_checks_ = 0;
   1008       }
   1009 
   1010       // Store the server-dictated poll interval, if any.
   1011       server_dictated_poll_interval_ =
   1012           std::max(0, omaha_request_action->GetOutputObject().poll_interval);
   1013     }
   1014   }
   1015   if (code != ErrorCode::kSuccess) {
   1016     // If the current state is at or past the download phase, count the failure
   1017     // in case a switch to full update becomes necessary. Ignore network
   1018     // transfer timeouts and failures.
   1019     if (status_ >= UpdateStatus::DOWNLOADING &&
   1020         code != ErrorCode::kDownloadTransferError) {
   1021       MarkDeltaUpdateFailure();
   1022     }
   1023     // On failure, schedule an error event to be sent to Omaha.
   1024     CreatePendingErrorEvent(action, code);
   1025     return;
   1026   }
   1027   // Find out which action completed.
   1028   if (type == OmahaResponseHandlerAction::StaticType()) {
   1029     // Note that the status will be updated to DOWNLOADING when some bytes get
   1030     // actually downloaded from the server and the BytesReceived callback is
   1031     // invoked. This avoids notifying the user that a download has started in
   1032     // cases when the server and the client are unable to initiate the download.
   1033     CHECK(action == response_handler_action_.get());
   1034     const InstallPlan& plan = response_handler_action_->install_plan();
   1035     UpdateLastCheckedTime();
   1036     new_version_ = plan.version;
   1037     new_payload_size_ = 0;
   1038     for (const auto& payload : plan.payloads)
   1039       new_payload_size_ += payload.size;
   1040     cpu_limiter_.StartLimiter();
   1041     SetStatusAndNotify(UpdateStatus::UPDATE_AVAILABLE);
   1042   } else if (type == DownloadAction::StaticType()) {
   1043     SetStatusAndNotify(UpdateStatus::FINALIZING);
   1044   }
   1045 }
   1046 
   1047 void UpdateAttempter::BytesReceived(uint64_t bytes_progressed,
   1048                                     uint64_t bytes_received,
   1049                                     uint64_t total) {
   1050   // The PayloadState keeps track of how many bytes were actually downloaded
   1051   // from a given URL for the URL skipping logic.
   1052   system_state_->payload_state()->DownloadProgress(bytes_progressed);
   1053 
   1054   double progress = 0;
   1055   if (total)
   1056     progress = static_cast<double>(bytes_received) / static_cast<double>(total);
   1057   if (status_ != UpdateStatus::DOWNLOADING || bytes_received == total) {
   1058     download_progress_ = progress;
   1059     SetStatusAndNotify(UpdateStatus::DOWNLOADING);
   1060   } else {
   1061     ProgressUpdate(progress);
   1062   }
   1063 }
   1064 
   1065 void UpdateAttempter::DownloadComplete() {
   1066   system_state_->payload_state()->DownloadComplete();
   1067 }
   1068 
   1069 void UpdateAttempter::ProgressUpdate(double progress) {
   1070   // Self throttle based on progress. Also send notifications if progress is
   1071   // too slow.
   1072   if (progress == 1.0 ||
   1073       progress - download_progress_ >= kBroadcastThresholdProgress ||
   1074       TimeTicks::Now() - last_notify_time_ >=
   1075           TimeDelta::FromSeconds(kBroadcastThresholdSeconds)) {
   1076     download_progress_ = progress;
   1077     BroadcastStatus();
   1078   }
   1079 }
   1080 
   1081 bool UpdateAttempter::ResetStatus() {
   1082   LOG(INFO) << "Attempting to reset state from "
   1083             << UpdateStatusToString(status_) << " to UpdateStatus::IDLE";
   1084 
   1085   switch (status_) {
   1086     case UpdateStatus::IDLE:
   1087       // no-op.
   1088       return true;
   1089 
   1090     case UpdateStatus::UPDATED_NEED_REBOOT:  {
   1091       bool ret_value = true;
   1092       status_ = UpdateStatus::IDLE;
   1093 
   1094       // Remove the reboot marker so that if the machine is rebooted
   1095       // after resetting to idle state, it doesn't go back to
   1096       // UpdateStatus::UPDATED_NEED_REBOOT state.
   1097       ret_value = prefs_->Delete(kPrefsUpdateCompletedOnBootId) && ret_value;
   1098       ret_value = prefs_->Delete(kPrefsUpdateCompletedBootTime) && ret_value;
   1099 
   1100       // Update the boot flags so the current slot has higher priority.
   1101       BootControlInterface* boot_control = system_state_->boot_control();
   1102       if (!boot_control->SetActiveBootSlot(boot_control->GetCurrentSlot()))
   1103         ret_value = false;
   1104 
   1105       // Mark the current slot as successful again, since marking it as active
   1106       // may reset the successful bit. We ignore the result of whether marking
   1107       // the current slot as successful worked.
   1108       if (!boot_control->MarkBootSuccessfulAsync(Bind([](bool successful){})))
   1109         ret_value = false;
   1110 
   1111       // Notify the PayloadState that the successful payload was canceled.
   1112       system_state_->payload_state()->ResetUpdateStatus();
   1113 
   1114       // The previous version is used to report back to omaha after reboot that
   1115       // we actually rebooted into the new version from this "prev-version". We
   1116       // need to clear out this value now to prevent it being sent on the next
   1117       // updatecheck request.
   1118       ret_value = prefs_->SetString(kPrefsPreviousVersion, "") && ret_value;
   1119 
   1120       LOG(INFO) << "Reset status " << (ret_value ? "successful" : "failed");
   1121       return ret_value;
   1122     }
   1123 
   1124     default:
   1125       LOG(ERROR) << "Reset not allowed in this state.";
   1126       return false;
   1127   }
   1128 }
   1129 
   1130 bool UpdateAttempter::GetStatus(int64_t* last_checked_time,
   1131                                 double* progress,
   1132                                 string* current_operation,
   1133                                 string* new_version,
   1134                                 int64_t* new_payload_size) {
   1135   *last_checked_time = last_checked_time_;
   1136   *progress = download_progress_;
   1137   *current_operation = UpdateStatusToString(status_);
   1138   *new_version = new_version_;
   1139   *new_payload_size = new_payload_size_;
   1140   return true;
   1141 }
   1142 
   1143 void UpdateAttempter::UpdateBootFlags() {
   1144   if (update_boot_flags_running_) {
   1145     LOG(INFO) << "Update boot flags running, nothing to do.";
   1146     return;
   1147   }
   1148   if (updated_boot_flags_) {
   1149     LOG(INFO) << "Already updated boot flags. Skipping.";
   1150     if (start_action_processor_) {
   1151       ScheduleProcessingStart();
   1152     }
   1153     return;
   1154   }
   1155   // This is purely best effort. Failures should be logged by Subprocess. Run
   1156   // the script asynchronously to avoid blocking the event loop regardless of
   1157   // the script runtime.
   1158   update_boot_flags_running_ = true;
   1159   LOG(INFO) << "Marking booted slot as good.";
   1160   if (!system_state_->boot_control()->MarkBootSuccessfulAsync(Bind(
   1161           &UpdateAttempter::CompleteUpdateBootFlags, base::Unretained(this)))) {
   1162     LOG(ERROR) << "Failed to mark current boot as successful.";
   1163     CompleteUpdateBootFlags(false);
   1164   }
   1165 }
   1166 
   1167 void UpdateAttempter::CompleteUpdateBootFlags(bool successful) {
   1168   update_boot_flags_running_ = false;
   1169   updated_boot_flags_ = true;
   1170   if (start_action_processor_) {
   1171     ScheduleProcessingStart();
   1172   }
   1173 }
   1174 
   1175 void UpdateAttempter::BroadcastStatus() {
   1176   for (const auto& observer : service_observers_) {
   1177     observer->SendStatusUpdate(last_checked_time_,
   1178                                download_progress_,
   1179                                status_,
   1180                                new_version_,
   1181                                new_payload_size_);
   1182   }
   1183   last_notify_time_ = TimeTicks::Now();
   1184 }
   1185 
   1186 uint32_t UpdateAttempter::GetErrorCodeFlags()  {
   1187   uint32_t flags = 0;
   1188 
   1189   if (!system_state_->hardware()->IsNormalBootMode())
   1190     flags |= static_cast<uint32_t>(ErrorCode::kDevModeFlag);
   1191 
   1192   if (response_handler_action_.get() &&
   1193       response_handler_action_->install_plan().is_resume)
   1194     flags |= static_cast<uint32_t>(ErrorCode::kResumedFlag);
   1195 
   1196   if (!system_state_->hardware()->IsOfficialBuild())
   1197     flags |= static_cast<uint32_t>(ErrorCode::kTestImageFlag);
   1198 
   1199   if (omaha_request_params_->update_url() !=
   1200       constants::kOmahaDefaultProductionURL) {
   1201     flags |= static_cast<uint32_t>(ErrorCode::kTestOmahaUrlFlag);
   1202   }
   1203 
   1204   return flags;
   1205 }
   1206 
   1207 bool UpdateAttempter::ShouldCancel(ErrorCode* cancel_reason) {
   1208   // Check if the channel we're attempting to update to is the same as the
   1209   // target channel currently chosen by the user.
   1210   OmahaRequestParams* params = system_state_->request_params();
   1211   if (params->download_channel() != params->target_channel()) {
   1212     LOG(ERROR) << "Aborting download as target channel: "
   1213                << params->target_channel()
   1214                << " is different from the download channel: "
   1215                << params->download_channel();
   1216     *cancel_reason = ErrorCode::kUpdateCanceledByChannelChange;
   1217     return true;
   1218   }
   1219 
   1220   return false;
   1221 }
   1222 
   1223 void UpdateAttempter::SetStatusAndNotify(UpdateStatus status) {
   1224   status_ = status;
   1225   BroadcastStatus();
   1226 }
   1227 
   1228 void UpdateAttempter::CreatePendingErrorEvent(AbstractAction* action,
   1229                                               ErrorCode code) {
   1230   if (error_event_.get()) {
   1231     // This shouldn't really happen.
   1232     LOG(WARNING) << "There's already an existing pending error event.";
   1233     return;
   1234   }
   1235 
   1236   // For now assume that a generic Omaha response action failure means that
   1237   // there's no update so don't send an event. Also, double check that the
   1238   // failure has not occurred while sending an error event -- in which case
   1239   // don't schedule another. This shouldn't really happen but just in case...
   1240   if ((action->Type() == OmahaResponseHandlerAction::StaticType() &&
   1241        code == ErrorCode::kError) ||
   1242       status_ == UpdateStatus::REPORTING_ERROR_EVENT) {
   1243     return;
   1244   }
   1245 
   1246   // Classify the code to generate the appropriate result so that
   1247   // the Borgmon charts show up the results correctly.
   1248   // Do this before calling GetErrorCodeForAction which could potentially
   1249   // augment the bit representation of code and thus cause no matches for
   1250   // the switch cases below.
   1251   OmahaEvent::Result event_result;
   1252   switch (code) {
   1253     case ErrorCode::kOmahaUpdateIgnoredPerPolicy:
   1254     case ErrorCode::kOmahaUpdateDeferredPerPolicy:
   1255     case ErrorCode::kOmahaUpdateDeferredForBackoff:
   1256       event_result = OmahaEvent::kResultUpdateDeferred;
   1257       break;
   1258     default:
   1259       event_result = OmahaEvent::kResultError;
   1260       break;
   1261   }
   1262 
   1263   code = GetErrorCodeForAction(action, code);
   1264   fake_update_success_ = code == ErrorCode::kPostinstallBootedFromFirmwareB;
   1265 
   1266   // Compute the final error code with all the bit flags to be sent to Omaha.
   1267   code = static_cast<ErrorCode>(
   1268       static_cast<uint32_t>(code) | GetErrorCodeFlags());
   1269   error_event_.reset(new OmahaEvent(OmahaEvent::kTypeUpdateComplete,
   1270                                     event_result,
   1271                                     code));
   1272 }
   1273 
   1274 bool UpdateAttempter::ScheduleErrorEventAction() {
   1275   if (error_event_.get() == nullptr)
   1276     return false;
   1277 
   1278   LOG(ERROR) << "Update failed.";
   1279   system_state_->payload_state()->UpdateFailed(error_event_->error_code);
   1280 
   1281   // Send it to Omaha.
   1282   LOG(INFO) << "Reporting the error event";
   1283   shared_ptr<OmahaRequestAction> error_event_action(
   1284       new OmahaRequestAction(system_state_,
   1285                              error_event_.release(),  // Pass ownership.
   1286                              brillo::make_unique_ptr(new LibcurlHttpFetcher(
   1287                                  GetProxyResolver(),
   1288                                  system_state_->hardware())),
   1289                              false));
   1290   actions_.push_back(shared_ptr<AbstractAction>(error_event_action));
   1291   processor_->EnqueueAction(error_event_action.get());
   1292   SetStatusAndNotify(UpdateStatus::REPORTING_ERROR_EVENT);
   1293   processor_->StartProcessing();
   1294   return true;
   1295 }
   1296 
   1297 void UpdateAttempter::ScheduleProcessingStart() {
   1298   LOG(INFO) << "Scheduling an action processor start.";
   1299   start_action_processor_ = false;
   1300   MessageLoop::current()->PostTask(
   1301       FROM_HERE,
   1302       Bind([](ActionProcessor* processor) { processor->StartProcessing(); },
   1303            base::Unretained(processor_.get())));
   1304 }
   1305 
   1306 void UpdateAttempter::DisableDeltaUpdateIfNeeded() {
   1307   int64_t delta_failures;
   1308   if (omaha_request_params_->delta_okay() &&
   1309       prefs_->GetInt64(kPrefsDeltaUpdateFailures, &delta_failures) &&
   1310       delta_failures >= kMaxDeltaUpdateFailures) {
   1311     LOG(WARNING) << "Too many delta update failures, forcing full update.";
   1312     omaha_request_params_->set_delta_okay(false);
   1313   }
   1314 }
   1315 
   1316 void UpdateAttempter::MarkDeltaUpdateFailure() {
   1317   // Don't try to resume a failed delta update.
   1318   DeltaPerformer::ResetUpdateProgress(prefs_, false);
   1319   int64_t delta_failures;
   1320   if (!prefs_->GetInt64(kPrefsDeltaUpdateFailures, &delta_failures) ||
   1321       delta_failures < 0) {
   1322     delta_failures = 0;
   1323   }
   1324   prefs_->SetInt64(kPrefsDeltaUpdateFailures, ++delta_failures);
   1325 }
   1326 
   1327 void UpdateAttempter::PingOmaha() {
   1328   if (!processor_->IsRunning()) {
   1329     shared_ptr<OmahaRequestAction> ping_action(new OmahaRequestAction(
   1330         system_state_,
   1331         nullptr,
   1332         brillo::make_unique_ptr(new LibcurlHttpFetcher(
   1333             GetProxyResolver(),
   1334             system_state_->hardware())),
   1335         true));
   1336     actions_.push_back(shared_ptr<OmahaRequestAction>(ping_action));
   1337     processor_->set_delegate(nullptr);
   1338     processor_->EnqueueAction(ping_action.get());
   1339     // Call StartProcessing() synchronously here to avoid any race conditions
   1340     // caused by multiple outstanding ping Omaha requests.  If we call
   1341     // StartProcessing() asynchronously, the device can be suspended before we
   1342     // get a chance to callback to StartProcessing().  When the device resumes
   1343     // (assuming the device sleeps longer than the next update check period),
   1344     // StartProcessing() is called back and at the same time, the next update
   1345     // check is fired which eventually invokes StartProcessing().  A crash
   1346     // can occur because StartProcessing() checks to make sure that the
   1347     // processor is idle which it isn't due to the two concurrent ping Omaha
   1348     // requests.
   1349     processor_->StartProcessing();
   1350   } else {
   1351     LOG(WARNING) << "Action processor running, Omaha ping suppressed.";
   1352   }
   1353 
   1354   // Update the last check time here; it may be re-updated when an Omaha
   1355   // response is received, but this will prevent us from repeatedly scheduling
   1356   // checks in the case where a response is not received.
   1357   UpdateLastCheckedTime();
   1358 
   1359   // Update the status which will schedule the next update check
   1360   SetStatusAndNotify(UpdateStatus::UPDATED_NEED_REBOOT);
   1361   ScheduleUpdates();
   1362 }
   1363 
   1364 
   1365 bool UpdateAttempter::DecrementUpdateCheckCount() {
   1366   int64_t update_check_count_value;
   1367 
   1368   if (!prefs_->Exists(kPrefsUpdateCheckCount)) {
   1369     // This file does not exist. This means we haven't started our update
   1370     // check count down yet, so nothing more to do. This file will be created
   1371     // later when we first satisfy the wall-clock-based-wait period.
   1372     LOG(INFO) << "No existing update check count. That's normal.";
   1373     return true;
   1374   }
   1375 
   1376   if (prefs_->GetInt64(kPrefsUpdateCheckCount, &update_check_count_value)) {
   1377     // Only if we're able to read a proper integer value, then go ahead
   1378     // and decrement and write back the result in the same file, if needed.
   1379     LOG(INFO) << "Update check count = " << update_check_count_value;
   1380 
   1381     if (update_check_count_value == 0) {
   1382       // It could be 0, if, for some reason, the file didn't get deleted
   1383       // when we set our status to waiting for reboot. so we just leave it
   1384       // as is so that we can prevent another update_check wait for this client.
   1385       LOG(INFO) << "Not decrementing update check count as it's already 0.";
   1386       return true;
   1387     }
   1388 
   1389     if (update_check_count_value > 0)
   1390       update_check_count_value--;
   1391     else
   1392       update_check_count_value = 0;
   1393 
   1394     // Write out the new value of update_check_count_value.
   1395     if (prefs_->SetInt64(kPrefsUpdateCheckCount, update_check_count_value)) {
   1396       // We successfully wrote out te new value, so enable the
   1397       // update check based wait.
   1398       LOG(INFO) << "New update check count = " << update_check_count_value;
   1399       return true;
   1400     }
   1401   }
   1402 
   1403   LOG(INFO) << "Deleting update check count state due to read/write errors.";
   1404 
   1405   // We cannot read/write to the file, so disable the update check based wait
   1406   // so that we don't get stuck in this OS version by any chance (which could
   1407   // happen if there's some bug that causes to read/write incorrectly).
   1408   // Also attempt to delete the file to do our best effort to cleanup.
   1409   prefs_->Delete(kPrefsUpdateCheckCount);
   1410   return false;
   1411 }
   1412 
   1413 
   1414 void UpdateAttempter::UpdateEngineStarted() {
   1415   // If we just booted into a new update, keep the previous OS version
   1416   // in case we rebooted because of a crash of the old version, so we
   1417   // can do a proper crash report with correct information.
   1418   // This must be done before calling
   1419   // system_state_->payload_state()->UpdateEngineStarted() since it will
   1420   // delete SystemUpdated marker file.
   1421   if (system_state_->system_rebooted() &&
   1422       prefs_->Exists(kPrefsSystemUpdatedMarker)) {
   1423     if (!prefs_->GetString(kPrefsPreviousVersion, &prev_version_)) {
   1424       // If we fail to get the version string, make sure it stays empty.
   1425       prev_version_.clear();
   1426     }
   1427   }
   1428 
   1429   system_state_->payload_state()->UpdateEngineStarted();
   1430   StartP2PAtStartup();
   1431 }
   1432 
   1433 bool UpdateAttempter::StartP2PAtStartup() {
   1434   if (system_state_ == nullptr ||
   1435       !system_state_->p2p_manager()->IsP2PEnabled()) {
   1436     LOG(INFO) << "Not starting p2p at startup since it's not enabled.";
   1437     return false;
   1438   }
   1439 
   1440   if (system_state_->p2p_manager()->CountSharedFiles() < 1) {
   1441     LOG(INFO) << "Not starting p2p at startup since our application "
   1442               << "is not sharing any files.";
   1443     return false;
   1444   }
   1445 
   1446   return StartP2PAndPerformHousekeeping();
   1447 }
   1448 
   1449 bool UpdateAttempter::StartP2PAndPerformHousekeeping() {
   1450   if (system_state_ == nullptr)
   1451     return false;
   1452 
   1453   if (!system_state_->p2p_manager()->IsP2PEnabled()) {
   1454     LOG(INFO) << "Not starting p2p since it's not enabled.";
   1455     return false;
   1456   }
   1457 
   1458   LOG(INFO) << "Ensuring that p2p is running.";
   1459   if (!system_state_->p2p_manager()->EnsureP2PRunning()) {
   1460     LOG(ERROR) << "Error starting p2p.";
   1461     return false;
   1462   }
   1463 
   1464   LOG(INFO) << "Performing p2p housekeeping.";
   1465   if (!system_state_->p2p_manager()->PerformHousekeeping()) {
   1466     LOG(ERROR) << "Error performing housekeeping for p2p.";
   1467     return false;
   1468   }
   1469 
   1470   LOG(INFO) << "Done performing p2p housekeeping.";
   1471   return true;
   1472 }
   1473 
   1474 bool UpdateAttempter::GetBootTimeAtUpdate(Time *out_boot_time) {
   1475   // In case of an update_engine restart without a reboot, we stored the boot_id
   1476   // when the update was completed by setting a pref, so we can check whether
   1477   // the last update was on this boot or a previous one.
   1478   string boot_id;
   1479   TEST_AND_RETURN_FALSE(utils::GetBootId(&boot_id));
   1480 
   1481   string update_completed_on_boot_id;
   1482   if (!prefs_->Exists(kPrefsUpdateCompletedOnBootId) ||
   1483       !prefs_->GetString(kPrefsUpdateCompletedOnBootId,
   1484                          &update_completed_on_boot_id) ||
   1485       update_completed_on_boot_id != boot_id)
   1486     return false;
   1487 
   1488   // Short-circuit avoiding the read in case out_boot_time is nullptr.
   1489   if (out_boot_time) {
   1490     int64_t boot_time = 0;
   1491     // Since the kPrefsUpdateCompletedOnBootId was correctly set, this pref
   1492     // should not fail.
   1493     TEST_AND_RETURN_FALSE(
   1494         prefs_->GetInt64(kPrefsUpdateCompletedBootTime, &boot_time));
   1495     *out_boot_time = Time::FromInternalValue(boot_time);
   1496   }
   1497   return true;
   1498 }
   1499 
   1500 bool UpdateAttempter::IsUpdateRunningOrScheduled() {
   1501   return ((status_ != UpdateStatus::IDLE &&
   1502            status_ != UpdateStatus::UPDATED_NEED_REBOOT) ||
   1503           waiting_for_scheduled_check_);
   1504 }
   1505 
   1506 bool UpdateAttempter::IsAnyUpdateSourceAllowed() {
   1507   // We allow updates from any source if either of these are true:
   1508   //  * The device is running an unofficial (dev/test) image.
   1509   //  * The debugd dev features are accessible (i.e. in devmode with no owner).
   1510   // This protects users running a base image, while still allowing a specific
   1511   // window (gated by the debug dev features) where `cros flash` is usable.
   1512   if (!system_state_->hardware()->IsOfficialBuild()) {
   1513     LOG(INFO) << "Non-official build; allowing any update source.";
   1514     return true;
   1515   }
   1516 
   1517   if (system_state_->hardware()->AreDevFeaturesEnabled()) {
   1518     LOG(INFO) << "Developer features enabled; allowing custom update sources.";
   1519     return true;
   1520   }
   1521 
   1522   LOG(INFO)
   1523       << "Developer features disabled; disallowing custom update sources.";
   1524   return false;
   1525 }
   1526 
   1527 }  // namespace chromeos_update_engine
   1528