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/omaha_request_action.h"
18
19#include <inttypes.h>
20
21#include <map>
22#include <sstream>
23#include <string>
24#include <vector>
25
26#include <base/bind.h>
27#include <base/logging.h>
28#include <base/rand_util.h>
29#include <base/strings/string_number_conversions.h>
30#include <base/strings/string_util.h>
31#include <base/strings/stringprintf.h>
32#include <base/time/time.h>
33#include <expat.h>
34#include <metrics/metrics_library.h>
35
36#include "update_engine/common/action_pipe.h"
37#include "update_engine/common/constants.h"
38#include "update_engine/common/hardware_interface.h"
39#include "update_engine/common/hash_calculator.h"
40#include "update_engine/common/platform_constants.h"
41#include "update_engine/common/prefs_interface.h"
42#include "update_engine/common/utils.h"
43#include "update_engine/connection_manager.h"
44#include "update_engine/metrics.h"
45#include "update_engine/metrics_utils.h"
46#include "update_engine/omaha_request_params.h"
47#include "update_engine/p2p_manager.h"
48#include "update_engine/payload_state_interface.h"
49
50using base::Time;
51using base::TimeDelta;
52using std::map;
53using std::string;
54using std::vector;
55
56namespace chromeos_update_engine {
57
58// List of custom pair tags that we interpret in the Omaha Response:
59static const char* kTagDeadline = "deadline";
60static const char* kTagDisablePayloadBackoff = "DisablePayloadBackoff";
61static const char* kTagVersion = "version";
62// Deprecated: "IsDelta"
63static const char* kTagIsDeltaPayload = "IsDeltaPayload";
64static const char* kTagMaxFailureCountPerUrl = "MaxFailureCountPerUrl";
65static const char* kTagMaxDaysToScatter = "MaxDaysToScatter";
66// Deprecated: "ManifestSignatureRsa"
67// Deprecated: "ManifestSize"
68static const char* kTagMetadataSignatureRsa = "MetadataSignatureRsa";
69static const char* kTagMetadataSize = "MetadataSize";
70static const char* kTagMoreInfo = "MoreInfo";
71// Deprecated: "NeedsAdmin"
72static const char* kTagPrompt = "Prompt";
73static const char* kTagSha256 = "sha256";
74static const char* kTagDisableP2PForDownloading = "DisableP2PForDownloading";
75static const char* kTagDisableP2PForSharing = "DisableP2PForSharing";
76static const char* kTagPublicKeyRsa = "PublicKeyRsa";
77
78static const char* kOmahaUpdaterVersion = "0.1.0.0";
79
80namespace {
81
82// Returns an XML ping element attribute assignment with attribute
83// |name| and value |ping_days| if |ping_days| has a value that needs
84// to be sent, or an empty string otherwise.
85string GetPingAttribute(const string& name, int ping_days) {
86  if (ping_days > 0 || ping_days == OmahaRequestAction::kNeverPinged)
87    return base::StringPrintf(" %s=\"%d\"", name.c_str(), ping_days);
88  return "";
89}
90
91// Returns an XML ping element if any of the elapsed days need to be
92// sent, or an empty string otherwise.
93string GetPingXml(int ping_active_days, int ping_roll_call_days) {
94  string ping_active = GetPingAttribute("a", ping_active_days);
95  string ping_roll_call = GetPingAttribute("r", ping_roll_call_days);
96  if (!ping_active.empty() || !ping_roll_call.empty()) {
97    return base::StringPrintf("        <ping active=\"1\"%s%s></ping>\n",
98                              ping_active.c_str(),
99                              ping_roll_call.c_str());
100  }
101  return "";
102}
103
104// Returns an XML that goes into the body of the <app> element of the Omaha
105// request based on the given parameters.
106string GetAppBody(const OmahaEvent* event,
107                  OmahaRequestParams* params,
108                  bool ping_only,
109                  bool include_ping,
110                  int ping_active_days,
111                  int ping_roll_call_days,
112                  PrefsInterface* prefs) {
113  string app_body;
114  if (event == nullptr) {
115    if (include_ping)
116        app_body = GetPingXml(ping_active_days, ping_roll_call_days);
117    if (!ping_only) {
118      app_body += base::StringPrintf(
119          "        <updatecheck targetversionprefix=\"%s\""
120          "></updatecheck>\n",
121          XmlEncodeWithDefault(params->target_version_prefix(), "").c_str());
122
123      // If this is the first update check after a reboot following a previous
124      // update, generate an event containing the previous version number. If
125      // the previous version preference file doesn't exist the event is still
126      // generated with a previous version of 0.0.0.0 -- this is relevant for
127      // older clients or new installs. The previous version event is not sent
128      // for ping-only requests because they come before the client has
129      // rebooted. The previous version event is also not sent if it was already
130      // sent for this new version with a previous updatecheck.
131      string prev_version;
132      if (!prefs->GetString(kPrefsPreviousVersion, &prev_version)) {
133        prev_version = "0.0.0.0";
134      }
135      // We only store a non-empty previous version value after a successful
136      // update in the previous boot. After reporting it back to the server,
137      // we clear the previous version value so it doesn't get reported again.
138      if (!prev_version.empty()) {
139        app_body += base::StringPrintf(
140            "        <event eventtype=\"%d\" eventresult=\"%d\" "
141            "previousversion=\"%s\"></event>\n",
142            OmahaEvent::kTypeRebootedAfterUpdate,
143            OmahaEvent::kResultSuccess,
144            XmlEncodeWithDefault(prev_version, "0.0.0.0").c_str());
145        LOG_IF(WARNING, !prefs->SetString(kPrefsPreviousVersion, ""))
146            << "Unable to reset the previous version.";
147      }
148    }
149  } else {
150    // The error code is an optional attribute so append it only if the result
151    // is not success.
152    string error_code;
153    if (event->result != OmahaEvent::kResultSuccess) {
154      error_code = base::StringPrintf(" errorcode=\"%d\"",
155                                      static_cast<int>(event->error_code));
156    }
157    app_body = base::StringPrintf(
158        "        <event eventtype=\"%d\" eventresult=\"%d\"%s></event>\n",
159        event->type, event->result, error_code.c_str());
160  }
161
162  return app_body;
163}
164
165// Returns the cohort* argument to include in the <app> tag for the passed
166// |arg_name| and |prefs_key|, if any. The return value is suitable to
167// concatenate to the list of arguments and includes a space at the end.
168string GetCohortArgXml(PrefsInterface* prefs,
169                       const string arg_name,
170                       const string prefs_key) {
171  // There's nothing wrong with not having a given cohort setting, so we check
172  // existance first to avoid the warning log message.
173  if (!prefs->Exists(prefs_key))
174    return "";
175  string cohort_value;
176  if (!prefs->GetString(prefs_key, &cohort_value) || cohort_value.empty())
177    return "";
178  // This is a sanity check to avoid sending a huge XML file back to Ohama due
179  // to a compromised stateful partition making the update check fail in low
180  // network environments envent after a reboot.
181  if (cohort_value.size() > 1024) {
182    LOG(WARNING) << "The omaha cohort setting " << arg_name
183                 << " has a too big value, which must be an error or an "
184                    "attacker trying to inhibit updates.";
185    return "";
186  }
187
188  string escaped_xml_value;
189  if (!XmlEncode(cohort_value, &escaped_xml_value)) {
190    LOG(WARNING) << "The omaha cohort setting " << arg_name
191                 << " is ASCII-7 invalid, ignoring it.";
192    return "";
193  }
194
195  return base::StringPrintf("%s=\"%s\" ",
196                            arg_name.c_str(), escaped_xml_value.c_str());
197}
198
199// Returns an XML that corresponds to the entire <app> node of the Omaha
200// request based on the given parameters.
201string GetAppXml(const OmahaEvent* event,
202                 OmahaRequestParams* params,
203                 bool ping_only,
204                 bool include_ping,
205                 int ping_active_days,
206                 int ping_roll_call_days,
207                 int install_date_in_days,
208                 SystemState* system_state) {
209  string app_body = GetAppBody(event, params, ping_only, include_ping,
210                               ping_active_days, ping_roll_call_days,
211                               system_state->prefs());
212  string app_versions;
213
214  // If we are upgrading to a more stable channel and we are allowed to do
215  // powerwash, then pass 0.0.0.0 as the version. This is needed to get the
216  // highest-versioned payload on the destination channel.
217  if (params->to_more_stable_channel() && params->is_powerwash_allowed()) {
218    LOG(INFO) << "Passing OS version as 0.0.0.0 as we are set to powerwash "
219              << "on downgrading to the version in the more stable channel";
220    app_versions = "version=\"0.0.0.0\" from_version=\"" +
221        XmlEncodeWithDefault(params->app_version(), "0.0.0.0") + "\" ";
222  } else {
223    app_versions = "version=\"" +
224        XmlEncodeWithDefault(params->app_version(), "0.0.0.0") + "\" ";
225  }
226
227  string download_channel = params->download_channel();
228  string app_channels =
229      "track=\"" + XmlEncodeWithDefault(download_channel, "") + "\" ";
230  if (params->current_channel() != download_channel) {
231    app_channels += "from_track=\"" + XmlEncodeWithDefault(
232        params->current_channel(), "") + "\" ";
233  }
234
235  string delta_okay_str = params->delta_okay() ? "true" : "false";
236
237  // If install_date_days is not set (e.g. its value is -1 ), don't
238  // include the attribute.
239  string install_date_in_days_str = "";
240  if (install_date_in_days >= 0) {
241    install_date_in_days_str = base::StringPrintf("installdate=\"%d\" ",
242                                                  install_date_in_days);
243  }
244
245  string app_cohort_args;
246  app_cohort_args += GetCohortArgXml(system_state->prefs(),
247                                     "cohort", kPrefsOmahaCohort);
248  app_cohort_args += GetCohortArgXml(system_state->prefs(),
249                                     "cohorthint", kPrefsOmahaCohortHint);
250  app_cohort_args += GetCohortArgXml(system_state->prefs(),
251                                     "cohortname", kPrefsOmahaCohortName);
252
253  string app_xml = "    <app "
254      "appid=\"" + XmlEncodeWithDefault(params->GetAppId(), "") + "\" " +
255      app_cohort_args +
256      app_versions +
257      app_channels +
258      "lang=\"" + XmlEncodeWithDefault(params->app_lang(), "en-US") + "\" " +
259      "board=\"" + XmlEncodeWithDefault(params->os_board(), "") + "\" " +
260      "hardware_class=\"" + XmlEncodeWithDefault(params->hwid(), "") + "\" " +
261      "delta_okay=\"" + delta_okay_str + "\" "
262      "fw_version=\"" + XmlEncodeWithDefault(params->fw_version(), "") + "\" " +
263      "ec_version=\"" + XmlEncodeWithDefault(params->ec_version(), "") + "\" " +
264      install_date_in_days_str +
265      ">\n" +
266         app_body +
267      "    </app>\n";
268
269  return app_xml;
270}
271
272// Returns an XML that corresponds to the entire <os> node of the Omaha
273// request based on the given parameters.
274string GetOsXml(OmahaRequestParams* params) {
275  string os_xml ="    <os "
276      "version=\"" + XmlEncodeWithDefault(params->os_version(), "") + "\" " +
277      "platform=\"" + XmlEncodeWithDefault(params->os_platform(), "") + "\" " +
278      "sp=\"" + XmlEncodeWithDefault(params->os_sp(), "") + "\">"
279      "</os>\n";
280  return os_xml;
281}
282
283// Returns an XML that corresponds to the entire Omaha request based on the
284// given parameters.
285string GetRequestXml(const OmahaEvent* event,
286                     OmahaRequestParams* params,
287                     bool ping_only,
288                     bool include_ping,
289                     int ping_active_days,
290                     int ping_roll_call_days,
291                     int install_date_in_days,
292                     SystemState* system_state) {
293  string os_xml = GetOsXml(params);
294  string app_xml = GetAppXml(event, params, ping_only, include_ping,
295                             ping_active_days, ping_roll_call_days,
296                             install_date_in_days, system_state);
297
298  string install_source = base::StringPrintf("installsource=\"%s\" ",
299      (params->interactive() ? "ondemandupdate" : "scheduler"));
300
301  string updater_version = XmlEncodeWithDefault(
302      base::StringPrintf("%s-%s",
303                         constants::kOmahaUpdaterID,
304                         kOmahaUpdaterVersion), "");
305  string request_xml =
306      "<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n"
307      "<request protocol=\"3.0\" " + (
308          "version=\"" + updater_version + "\" "
309          "updaterversion=\"" + updater_version + "\" " +
310          install_source +
311          "ismachine=\"1\">\n") +
312      os_xml +
313      app_xml +
314      "</request>\n";
315
316  return request_xml;
317}
318
319}  // namespace
320
321// Struct used for holding data obtained when parsing the XML.
322struct OmahaParserData {
323  explicit OmahaParserData(XML_Parser _xml_parser) : xml_parser(_xml_parser) {}
324
325  // Pointer to the expat XML_Parser object.
326  XML_Parser xml_parser;
327
328  // This is the state of the parser as it's processing the XML.
329  bool failed = false;
330  bool entity_decl = false;
331  string current_path;
332
333  // These are the values extracted from the XML.
334  string app_cohort;
335  string app_cohorthint;
336  string app_cohortname;
337  bool app_cohort_set = false;
338  bool app_cohorthint_set = false;
339  bool app_cohortname_set = false;
340  string updatecheck_status;
341  string updatecheck_poll_interval;
342  string daystart_elapsed_days;
343  string daystart_elapsed_seconds;
344  vector<string> url_codebase;
345  string package_name;
346  string package_size;
347  string manifest_version;
348  map<string, string> action_postinstall_attrs;
349};
350
351namespace {
352
353// Callback function invoked by expat.
354void ParserHandlerStart(void* user_data, const XML_Char* element,
355                        const XML_Char** attr) {
356  OmahaParserData* data = reinterpret_cast<OmahaParserData*>(user_data);
357
358  if (data->failed)
359    return;
360
361  data->current_path += string("/") + element;
362
363  map<string, string> attrs;
364  if (attr != nullptr) {
365    for (int n = 0; attr[n] != nullptr && attr[n+1] != nullptr; n += 2) {
366      string key = attr[n];
367      string value = attr[n + 1];
368      attrs[key] = value;
369    }
370  }
371
372  if (data->current_path == "/response/app") {
373    if (attrs.find("cohort") != attrs.end()) {
374      data->app_cohort_set = true;
375      data->app_cohort = attrs["cohort"];
376    }
377    if (attrs.find("cohorthint") != attrs.end()) {
378      data->app_cohorthint_set = true;
379      data->app_cohorthint = attrs["cohorthint"];
380    }
381    if (attrs.find("cohortname") != attrs.end()) {
382      data->app_cohortname_set = true;
383      data->app_cohortname = attrs["cohortname"];
384    }
385  } else if (data->current_path == "/response/app/updatecheck") {
386    // There is only supposed to be a single <updatecheck> element.
387    data->updatecheck_status = attrs["status"];
388    data->updatecheck_poll_interval = attrs["PollInterval"];
389  } else if (data->current_path == "/response/daystart") {
390    // Get the install-date.
391    data->daystart_elapsed_days = attrs["elapsed_days"];
392    data->daystart_elapsed_seconds = attrs["elapsed_seconds"];
393  } else if (data->current_path == "/response/app/updatecheck/urls/url") {
394    // Look at all <url> elements.
395    data->url_codebase.push_back(attrs["codebase"]);
396  } else if (data->package_name.empty() && data->current_path ==
397             "/response/app/updatecheck/manifest/packages/package") {
398    // Only look at the first <package>.
399    data->package_name = attrs["name"];
400    data->package_size = attrs["size"];
401  } else if (data->current_path == "/response/app/updatecheck/manifest") {
402    // Get the version.
403    data->manifest_version = attrs[kTagVersion];
404  } else if (data->current_path ==
405             "/response/app/updatecheck/manifest/actions/action") {
406    // We only care about the postinstall action.
407    if (attrs["event"] == "postinstall") {
408      data->action_postinstall_attrs = attrs;
409    }
410  }
411}
412
413// Callback function invoked by expat.
414void ParserHandlerEnd(void* user_data, const XML_Char* element) {
415  OmahaParserData* data = reinterpret_cast<OmahaParserData*>(user_data);
416  if (data->failed)
417    return;
418
419  const string path_suffix = string("/") + element;
420
421  if (!base::EndsWith(data->current_path, path_suffix,
422                      base::CompareCase::SENSITIVE)) {
423    LOG(ERROR) << "Unexpected end element '" << element
424               << "' with current_path='" << data->current_path << "'";
425    data->failed = true;
426    return;
427  }
428  data->current_path.resize(data->current_path.size() - path_suffix.size());
429}
430
431// Callback function invoked by expat.
432//
433// This is called for entity declarations. Since Omaha is guaranteed
434// to never return any XML with entities our course of action is to
435// just stop parsing. This avoids potential resource exhaustion
436// problems AKA the "billion laughs". CVE-2013-0340.
437void ParserHandlerEntityDecl(void *user_data,
438                             const XML_Char *entity_name,
439                             int is_parameter_entity,
440                             const XML_Char *value,
441                             int value_length,
442                             const XML_Char *base,
443                             const XML_Char *system_id,
444                             const XML_Char *public_id,
445                             const XML_Char *notation_name) {
446  OmahaParserData* data = reinterpret_cast<OmahaParserData*>(user_data);
447
448  LOG(ERROR) << "XML entities are not supported. Aborting parsing.";
449  data->failed = true;
450  data->entity_decl = true;
451  XML_StopParser(data->xml_parser, false);
452}
453
454}  // namespace
455
456bool XmlEncode(const string& input, string* output) {
457  if (std::find_if(input.begin(), input.end(),
458                   [](const char c){return c & 0x80;}) != input.end()) {
459    LOG(WARNING) << "Invalid ASCII-7 string passed to the XML encoder:";
460    utils::HexDumpString(input);
461    return false;
462  }
463  output->clear();
464  // We need at least input.size() space in the output, but the code below will
465  // handle it if we need more.
466  output->reserve(input.size());
467  for (char c : input) {
468    switch (c) {
469      case '\"':
470        output->append("&quot;");
471        break;
472      case '\'':
473        output->append("&apos;");
474        break;
475      case '&':
476        output->append("&amp;");
477        break;
478      case '<':
479        output->append("&lt;");
480        break;
481      case '>':
482        output->append("&gt;");
483        break;
484      default:
485        output->push_back(c);
486    }
487  }
488  return true;
489}
490
491string XmlEncodeWithDefault(const string& input, const string& default_value) {
492  string output;
493  if (XmlEncode(input, &output))
494    return output;
495  return default_value;
496}
497
498OmahaRequestAction::OmahaRequestAction(
499    SystemState* system_state,
500    OmahaEvent* event,
501    std::unique_ptr<HttpFetcher> http_fetcher,
502    bool ping_only)
503    : system_state_(system_state),
504      event_(event),
505      http_fetcher_(std::move(http_fetcher)),
506      ping_only_(ping_only),
507      ping_active_days_(0),
508      ping_roll_call_days_(0) {
509  params_ = system_state->request_params();
510}
511
512OmahaRequestAction::~OmahaRequestAction() {}
513
514// Calculates the value to use for the ping days parameter.
515int OmahaRequestAction::CalculatePingDays(const string& key) {
516  int days = kNeverPinged;
517  int64_t last_ping = 0;
518  if (system_state_->prefs()->GetInt64(key, &last_ping) && last_ping >= 0) {
519    days = (Time::Now() - Time::FromInternalValue(last_ping)).InDays();
520    if (days < 0) {
521      // If |days| is negative, then the system clock must have jumped
522      // back in time since the ping was sent. Mark the value so that
523      // it doesn't get sent to the server but we still update the
524      // last ping daystart preference. This way the next ping time
525      // will be correct, hopefully.
526      days = kPingTimeJump;
527      LOG(WARNING) <<
528          "System clock jumped back in time. Resetting ping daystarts.";
529    }
530  }
531  return days;
532}
533
534void OmahaRequestAction::InitPingDays() {
535  // We send pings only along with update checks, not with events.
536  if (IsEvent()) {
537    return;
538  }
539  // TODO(petkov): Figure a way to distinguish active use pings
540  // vs. roll call pings. Currently, the two pings are identical. A
541  // fix needs to change this code as well as UpdateLastPingDays and ShouldPing.
542  ping_active_days_ = CalculatePingDays(kPrefsLastActivePingDay);
543  ping_roll_call_days_ = CalculatePingDays(kPrefsLastRollCallPingDay);
544}
545
546bool OmahaRequestAction::ShouldPing() const {
547  if (ping_active_days_ == OmahaRequestAction::kNeverPinged &&
548      ping_roll_call_days_ == OmahaRequestAction::kNeverPinged) {
549    int powerwash_count = system_state_->hardware()->GetPowerwashCount();
550    if (powerwash_count > 0) {
551      LOG(INFO) << "Not sending ping with a=-1 r=-1 to omaha because "
552                << "powerwash_count is " << powerwash_count;
553      return false;
554    }
555    return true;
556  }
557  return ping_active_days_ > 0 || ping_roll_call_days_ > 0;
558}
559
560// static
561int OmahaRequestAction::GetInstallDate(SystemState* system_state) {
562  PrefsInterface* prefs = system_state->prefs();
563  if (prefs == nullptr)
564    return -1;
565
566  // If we have the value stored on disk, just return it.
567  int64_t stored_value;
568  if (prefs->GetInt64(kPrefsInstallDateDays, &stored_value)) {
569    // Convert and sanity-check.
570    int install_date_days = static_cast<int>(stored_value);
571    if (install_date_days >= 0)
572      return install_date_days;
573    LOG(ERROR) << "Dropping stored Omaha InstallData since its value num_days="
574               << install_date_days << " looks suspicious.";
575    prefs->Delete(kPrefsInstallDateDays);
576  }
577
578  // Otherwise, if OOBE is not complete then do nothing and wait for
579  // ParseResponse() to call ParseInstallDate() and then
580  // PersistInstallDate() to set the kPrefsInstallDateDays state
581  // variable. Once that is done, we'll then report back in future
582  // Omaha requests.  This works exactly because OOBE triggers an
583  // update check.
584  //
585  // However, if OOBE is complete and the kPrefsInstallDateDays state
586  // variable is not set, there are two possibilities
587  //
588  //   1. The update check in OOBE failed so we never got a response
589  //      from Omaha (no network etc.); or
590  //
591  //   2. OOBE was done on an older version that didn't write to the
592  //      kPrefsInstallDateDays state variable.
593  //
594  // In both cases, we approximate the install date by simply
595  // inspecting the timestamp of when OOBE happened.
596
597  Time time_of_oobe;
598  if (!system_state->hardware()->IsOOBEComplete(&time_of_oobe)) {
599    LOG(INFO) << "Not generating Omaha InstallData as we have "
600              << "no prefs file and OOBE is not complete.";
601    return -1;
602  }
603
604  int num_days;
605  if (!utils::ConvertToOmahaInstallDate(time_of_oobe, &num_days)) {
606    LOG(ERROR) << "Not generating Omaha InstallData from time of OOBE "
607               << "as its value '" << utils::ToString(time_of_oobe)
608               << "' looks suspicious.";
609    return -1;
610  }
611
612  // Persist this to disk, for future use.
613  if (!OmahaRequestAction::PersistInstallDate(system_state,
614                                              num_days,
615                                              kProvisionedFromOOBEMarker))
616    return -1;
617
618  LOG(INFO) << "Set the Omaha InstallDate from OOBE time-stamp to "
619            << num_days << " days";
620
621  return num_days;
622}
623
624void OmahaRequestAction::PerformAction() {
625  http_fetcher_->set_delegate(this);
626  InitPingDays();
627  if (ping_only_ && !ShouldPing()) {
628    processor_->ActionComplete(this, ErrorCode::kSuccess);
629    return;
630  }
631
632  string request_post(GetRequestXml(event_.get(),
633                                    params_,
634                                    ping_only_,
635                                    ShouldPing(),  // include_ping
636                                    ping_active_days_,
637                                    ping_roll_call_days_,
638                                    GetInstallDate(system_state_),
639                                    system_state_));
640
641  http_fetcher_->SetPostData(request_post.data(), request_post.size(),
642                             kHttpContentTypeTextXml);
643  LOG(INFO) << "Posting an Omaha request to " << params_->update_url();
644  LOG(INFO) << "Request: " << request_post;
645  http_fetcher_->BeginTransfer(params_->update_url());
646}
647
648void OmahaRequestAction::TerminateProcessing() {
649  http_fetcher_->TerminateTransfer();
650}
651
652// We just store the response in the buffer. Once we've received all bytes,
653// we'll look in the buffer and decide what to do.
654void OmahaRequestAction::ReceivedBytes(HttpFetcher *fetcher,
655                                       const void* bytes,
656                                       size_t length) {
657  const uint8_t* byte_ptr = reinterpret_cast<const uint8_t*>(bytes);
658  response_buffer_.insert(response_buffer_.end(), byte_ptr, byte_ptr + length);
659}
660
661namespace {
662
663// Parses a 64 bit base-10 int from a string and returns it. Returns 0
664// on error. If the string contains "0", that's indistinguishable from
665// error.
666off_t ParseInt(const string& str) {
667  off_t ret = 0;
668  int rc = sscanf(str.c_str(), "%" PRIi64, &ret);  // NOLINT(runtime/printf)
669  if (rc < 1) {
670    // failure
671    return 0;
672  }
673  return ret;
674}
675
676// Parses |str| and returns |true| if, and only if, its value is "true".
677bool ParseBool(const string& str) {
678  return str == "true";
679}
680
681// Update the last ping day preferences based on the server daystart
682// response. Returns true on success, false otherwise.
683bool UpdateLastPingDays(OmahaParserData *parser_data, PrefsInterface* prefs) {
684  int64_t elapsed_seconds = 0;
685  TEST_AND_RETURN_FALSE(
686      base::StringToInt64(parser_data->daystart_elapsed_seconds,
687                          &elapsed_seconds));
688  TEST_AND_RETURN_FALSE(elapsed_seconds >= 0);
689
690  // Remember the local time that matches the server's last midnight
691  // time.
692  Time daystart = Time::Now() - TimeDelta::FromSeconds(elapsed_seconds);
693  prefs->SetInt64(kPrefsLastActivePingDay, daystart.ToInternalValue());
694  prefs->SetInt64(kPrefsLastRollCallPingDay, daystart.ToInternalValue());
695  return true;
696}
697}  // namespace
698
699bool OmahaRequestAction::ParseResponse(OmahaParserData* parser_data,
700                                       OmahaResponse* output_object,
701                                       ScopedActionCompleter* completer) {
702  if (parser_data->updatecheck_status.empty()) {
703    completer->set_code(ErrorCode::kOmahaResponseInvalid);
704    return false;
705  }
706
707  // chromium-os:37289: The PollInterval is not supported by Omaha server
708  // currently.  But still keeping this existing code in case we ever decide to
709  // slow down the request rate from the server-side. Note that the PollInterval
710  // is not persisted, so it has to be sent by the server on every response to
711  // guarantee that the scheduler uses this value (otherwise, if the device got
712  // rebooted after the last server-indicated value, it'll revert to the default
713  // value). Also kDefaultMaxUpdateChecks value for the scattering logic is
714  // based on the assumption that we perform an update check every hour so that
715  // the max value of 8 will roughly be equivalent to one work day. If we decide
716  // to use PollInterval permanently, we should update the
717  // max_update_checks_allowed to take PollInterval into account.  Note: The
718  // parsing for PollInterval happens even before parsing of the status because
719  // we may want to specify the PollInterval even when there's no update.
720  base::StringToInt(parser_data->updatecheck_poll_interval,
721                    &output_object->poll_interval);
722
723  // Check for the "elapsed_days" attribute in the "daystart"
724  // element. This is the number of days since Jan 1 2007, 0:00
725  // PST. If we don't have a persisted value of the Omaha InstallDate,
726  // we'll use it to calculate it and then persist it.
727  if (ParseInstallDate(parser_data, output_object) &&
728      !HasInstallDate(system_state_)) {
729    // Since output_object->install_date_days is never negative, the
730    // elapsed_days -> install-date calculation is reduced to simply
731    // rounding down to the nearest number divisible by 7.
732    int remainder = output_object->install_date_days % 7;
733    int install_date_days_rounded =
734        output_object->install_date_days - remainder;
735    if (PersistInstallDate(system_state_,
736                           install_date_days_rounded,
737                           kProvisionedFromOmahaResponse)) {
738      LOG(INFO) << "Set the Omaha InstallDate from Omaha Response to "
739                << install_date_days_rounded << " days";
740    }
741  }
742
743  // We persist the cohorts sent by omaha even if the status is "noupdate".
744  if (parser_data->app_cohort_set)
745    PersistCohortData(kPrefsOmahaCohort, parser_data->app_cohort);
746  if (parser_data->app_cohorthint_set)
747    PersistCohortData(kPrefsOmahaCohortHint, parser_data->app_cohorthint);
748  if (parser_data->app_cohortname_set)
749    PersistCohortData(kPrefsOmahaCohortName, parser_data->app_cohortname);
750
751  if (!ParseStatus(parser_data, output_object, completer))
752    return false;
753
754  // Note: ParseUrls MUST be called before ParsePackage as ParsePackage
755  // appends the package name to the URLs populated in this method.
756  if (!ParseUrls(parser_data, output_object, completer))
757    return false;
758
759  if (!ParsePackage(parser_data, output_object, completer))
760    return false;
761
762  if (!ParseParams(parser_data, output_object, completer))
763    return false;
764
765  return true;
766}
767
768bool OmahaRequestAction::ParseStatus(OmahaParserData* parser_data,
769                                     OmahaResponse* output_object,
770                                     ScopedActionCompleter* completer) {
771  const string& status = parser_data->updatecheck_status;
772  if (status == "noupdate") {
773    LOG(INFO) << "No update.";
774    output_object->update_exists = false;
775    SetOutputObject(*output_object);
776    completer->set_code(ErrorCode::kSuccess);
777    return false;
778  }
779
780  if (status != "ok") {
781    LOG(ERROR) << "Unknown Omaha response status: " << status;
782    completer->set_code(ErrorCode::kOmahaResponseInvalid);
783    return false;
784  }
785
786  return true;
787}
788
789bool OmahaRequestAction::ParseUrls(OmahaParserData* parser_data,
790                                   OmahaResponse* output_object,
791                                   ScopedActionCompleter* completer) {
792  if (parser_data->url_codebase.empty()) {
793    LOG(ERROR) << "No Omaha Response URLs";
794    completer->set_code(ErrorCode::kOmahaResponseInvalid);
795    return false;
796  }
797
798  LOG(INFO) << "Found " << parser_data->url_codebase.size() << " url(s)";
799  output_object->payload_urls.clear();
800  for (const auto& codebase : parser_data->url_codebase) {
801    if (codebase.empty()) {
802      LOG(ERROR) << "Omaha Response URL has empty codebase";
803      completer->set_code(ErrorCode::kOmahaResponseInvalid);
804      return false;
805    }
806    output_object->payload_urls.push_back(codebase);
807  }
808
809  return true;
810}
811
812bool OmahaRequestAction::ParsePackage(OmahaParserData* parser_data,
813                                      OmahaResponse* output_object,
814                                      ScopedActionCompleter* completer) {
815  if (parser_data->package_name.empty()) {
816    LOG(ERROR) << "Omaha Response has empty package name";
817    completer->set_code(ErrorCode::kOmahaResponseInvalid);
818    return false;
819  }
820
821  // Append the package name to each URL in our list so that we don't
822  // propagate the urlBase vs packageName distinctions beyond this point.
823  // From now on, we only need to use payload_urls.
824  for (auto& payload_url : output_object->payload_urls)
825    payload_url += parser_data->package_name;
826
827  // Parse the payload size.
828  off_t size = ParseInt(parser_data->package_size);
829  if (size <= 0) {
830    LOG(ERROR) << "Omaha Response has invalid payload size: " << size;
831    completer->set_code(ErrorCode::kOmahaResponseInvalid);
832    return false;
833  }
834  output_object->size = size;
835
836  LOG(INFO) << "Payload size = " << output_object->size << " bytes";
837
838  return true;
839}
840
841bool OmahaRequestAction::ParseParams(OmahaParserData* parser_data,
842                                     OmahaResponse* output_object,
843                                     ScopedActionCompleter* completer) {
844  output_object->version = parser_data->manifest_version;
845  if (output_object->version.empty()) {
846    LOG(ERROR) << "Omaha Response does not have version in manifest!";
847    completer->set_code(ErrorCode::kOmahaResponseInvalid);
848    return false;
849  }
850
851  LOG(INFO) << "Received omaha response to update to version "
852            << output_object->version;
853
854  map<string, string> attrs = parser_data->action_postinstall_attrs;
855  if (attrs.empty()) {
856    LOG(ERROR) << "Omaha Response has no postinstall event action";
857    completer->set_code(ErrorCode::kOmahaResponseInvalid);
858    return false;
859  }
860
861  output_object->hash = attrs[kTagSha256];
862  if (output_object->hash.empty()) {
863    LOG(ERROR) << "Omaha Response has empty sha256 value";
864    completer->set_code(ErrorCode::kOmahaResponseInvalid);
865    return false;
866  }
867
868  // Get the optional properties one by one.
869  output_object->more_info_url = attrs[kTagMoreInfo];
870  output_object->metadata_size = ParseInt(attrs[kTagMetadataSize]);
871  output_object->metadata_signature = attrs[kTagMetadataSignatureRsa];
872  output_object->prompt = ParseBool(attrs[kTagPrompt]);
873  output_object->deadline = attrs[kTagDeadline];
874  output_object->max_days_to_scatter = ParseInt(attrs[kTagMaxDaysToScatter]);
875  output_object->disable_p2p_for_downloading =
876      ParseBool(attrs[kTagDisableP2PForDownloading]);
877  output_object->disable_p2p_for_sharing =
878      ParseBool(attrs[kTagDisableP2PForSharing]);
879  output_object->public_key_rsa = attrs[kTagPublicKeyRsa];
880
881  string max = attrs[kTagMaxFailureCountPerUrl];
882  if (!base::StringToUint(max, &output_object->max_failure_count_per_url))
883    output_object->max_failure_count_per_url = kDefaultMaxFailureCountPerUrl;
884
885  output_object->is_delta_payload = ParseBool(attrs[kTagIsDeltaPayload]);
886
887  output_object->disable_payload_backoff =
888      ParseBool(attrs[kTagDisablePayloadBackoff]);
889
890  return true;
891}
892
893// If the transfer was successful, this uses expat to parse the response
894// and fill in the appropriate fields of the output object. Also, notifies
895// the processor that we're done.
896void OmahaRequestAction::TransferComplete(HttpFetcher *fetcher,
897                                          bool successful) {
898  ScopedActionCompleter completer(processor_, this);
899  string current_response(response_buffer_.begin(), response_buffer_.end());
900  LOG(INFO) << "Omaha request response: " << current_response;
901
902  PayloadStateInterface* const payload_state = system_state_->payload_state();
903
904  // Events are best effort transactions -- assume they always succeed.
905  if (IsEvent()) {
906    CHECK(!HasOutputPipe()) << "No output pipe allowed for event requests.";
907    if (event_->result == OmahaEvent::kResultError && successful &&
908        system_state_->hardware()->IsOfficialBuild()) {
909      LOG(INFO) << "Signalling Crash Reporter.";
910      utils::ScheduleCrashReporterUpload();
911    }
912    completer.set_code(ErrorCode::kSuccess);
913    return;
914  }
915
916  if (!successful) {
917    LOG(ERROR) << "Omaha request network transfer failed.";
918    int code = GetHTTPResponseCode();
919    // Makes sure we send sane error values.
920    if (code < 0 || code >= 1000) {
921      code = 999;
922    }
923    completer.set_code(static_cast<ErrorCode>(
924        static_cast<int>(ErrorCode::kOmahaRequestHTTPResponseBase) + code));
925    return;
926  }
927
928  XML_Parser parser = XML_ParserCreate(nullptr);
929  OmahaParserData parser_data(parser);
930  XML_SetUserData(parser, &parser_data);
931  XML_SetElementHandler(parser, ParserHandlerStart, ParserHandlerEnd);
932  XML_SetEntityDeclHandler(parser, ParserHandlerEntityDecl);
933  XML_Status res = XML_Parse(
934      parser,
935      reinterpret_cast<const char*>(response_buffer_.data()),
936      response_buffer_.size(),
937      XML_TRUE);
938  XML_ParserFree(parser);
939
940  if (res != XML_STATUS_OK || parser_data.failed) {
941    LOG(ERROR) << "Omaha response not valid XML: "
942               << XML_ErrorString(XML_GetErrorCode(parser))
943               << " at line " << XML_GetCurrentLineNumber(parser)
944               << " col " << XML_GetCurrentColumnNumber(parser);
945    ErrorCode error_code = ErrorCode::kOmahaRequestXMLParseError;
946    if (response_buffer_.empty()) {
947      error_code = ErrorCode::kOmahaRequestEmptyResponseError;
948    } else if (parser_data.entity_decl) {
949      error_code = ErrorCode::kOmahaRequestXMLHasEntityDecl;
950    }
951    completer.set_code(error_code);
952    return;
953  }
954
955  // Update the last ping day preferences based on the server daystart response
956  // even if we didn't send a ping. Omaha always includes the daystart in the
957  // response, but log the error if it didn't.
958  LOG_IF(ERROR, !UpdateLastPingDays(&parser_data, system_state_->prefs()))
959      << "Failed to update the last ping day preferences!";
960
961  if (!HasOutputPipe()) {
962    // Just set success to whether or not the http transfer succeeded,
963    // which must be true at this point in the code.
964    completer.set_code(ErrorCode::kSuccess);
965    return;
966  }
967
968  OmahaResponse output_object;
969  if (!ParseResponse(&parser_data, &output_object, &completer))
970    return;
971  output_object.update_exists = true;
972  SetOutputObject(output_object);
973
974  if (ShouldIgnoreUpdate(output_object)) {
975    output_object.update_exists = false;
976    completer.set_code(ErrorCode::kOmahaUpdateIgnoredPerPolicy);
977    return;
978  }
979
980  // If Omaha says to disable p2p, respect that
981  if (output_object.disable_p2p_for_downloading) {
982    LOG(INFO) << "Forcibly disabling use of p2p for downloading as "
983              << "requested by Omaha.";
984    payload_state->SetUsingP2PForDownloading(false);
985  }
986  if (output_object.disable_p2p_for_sharing) {
987    LOG(INFO) << "Forcibly disabling use of p2p for sharing as "
988              << "requested by Omaha.";
989    payload_state->SetUsingP2PForSharing(false);
990  }
991
992  // Update the payload state with the current response. The payload state
993  // will automatically reset all stale state if this response is different
994  // from what's stored already. We are updating the payload state as late
995  // as possible in this method so that if a new release gets pushed and then
996  // got pulled back due to some issues, we don't want to clear our internal
997  // state unnecessarily.
998  payload_state->SetResponse(output_object);
999
1000  // It could be we've already exceeded the deadline for when p2p is
1001  // allowed or that we've tried too many times with p2p. Check that.
1002  if (payload_state->GetUsingP2PForDownloading()) {
1003    payload_state->P2PNewAttempt();
1004    if (!payload_state->P2PAttemptAllowed()) {
1005      LOG(INFO) << "Forcibly disabling use of p2p for downloading because "
1006                << "of previous failures when using p2p.";
1007      payload_state->SetUsingP2PForDownloading(false);
1008    }
1009  }
1010
1011  // From here on, we'll complete stuff in CompleteProcessing() so
1012  // disable |completer| since we'll create a new one in that
1013  // function.
1014  completer.set_should_complete(false);
1015
1016  // If we're allowed to use p2p for downloading we do not pay
1017  // attention to wall-clock-based waiting if the URL is indeed
1018  // available via p2p. Therefore, check if the file is available via
1019  // p2p before deferring...
1020  if (payload_state->GetUsingP2PForDownloading()) {
1021    LookupPayloadViaP2P(output_object);
1022  } else {
1023    CompleteProcessing();
1024  }
1025}
1026
1027void OmahaRequestAction::CompleteProcessing() {
1028  ScopedActionCompleter completer(processor_, this);
1029  OmahaResponse& output_object = const_cast<OmahaResponse&>(GetOutputObject());
1030  PayloadStateInterface* payload_state = system_state_->payload_state();
1031
1032  if (ShouldDeferDownload(&output_object)) {
1033    output_object.update_exists = false;
1034    LOG(INFO) << "Ignoring Omaha updates as updates are deferred by policy.";
1035    completer.set_code(ErrorCode::kOmahaUpdateDeferredPerPolicy);
1036    return;
1037  }
1038
1039  if (payload_state->ShouldBackoffDownload()) {
1040    output_object.update_exists = false;
1041    LOG(INFO) << "Ignoring Omaha updates in order to backoff our retry "
1042              << "attempts";
1043    completer.set_code(ErrorCode::kOmahaUpdateDeferredForBackoff);
1044    return;
1045  }
1046  completer.set_code(ErrorCode::kSuccess);
1047}
1048
1049void OmahaRequestAction::OnLookupPayloadViaP2PCompleted(const string& url) {
1050  LOG(INFO) << "Lookup complete, p2p-client returned URL '" << url << "'";
1051  if (!url.empty()) {
1052    system_state_->payload_state()->SetP2PUrl(url);
1053  } else {
1054    LOG(INFO) << "Forcibly disabling use of p2p for downloading "
1055              << "because no suitable peer could be found.";
1056    system_state_->payload_state()->SetUsingP2PForDownloading(false);
1057  }
1058  CompleteProcessing();
1059}
1060
1061void OmahaRequestAction::LookupPayloadViaP2P(const OmahaResponse& response) {
1062  // If the device is in the middle of an update, the state variables
1063  // kPrefsUpdateStateNextDataOffset, kPrefsUpdateStateNextDataLength
1064  // tracks the offset and length of the operation currently in
1065  // progress. The offset is based from the end of the manifest which
1066  // is kPrefsManifestMetadataSize bytes long.
1067  //
1068  // To make forward progress and avoid deadlocks, we need to find a
1069  // peer that has at least the entire operation we're currently
1070  // working on. Otherwise we may end up in a situation where two
1071  // devices bounce back and forth downloading from each other,
1072  // neither making any forward progress until one of them decides to
1073  // stop using p2p (via kMaxP2PAttempts and kMaxP2PAttemptTimeSeconds
1074  // safe-guards). See http://crbug.com/297170 for an example)
1075  size_t minimum_size = 0;
1076  int64_t manifest_metadata_size = 0;
1077  int64_t manifest_signature_size = 0;
1078  int64_t next_data_offset = 0;
1079  int64_t next_data_length = 0;
1080  if (system_state_ &&
1081      system_state_->prefs()->GetInt64(kPrefsManifestMetadataSize,
1082                                       &manifest_metadata_size) &&
1083      manifest_metadata_size != -1 &&
1084      system_state_->prefs()->GetInt64(kPrefsManifestSignatureSize,
1085                                       &manifest_signature_size) &&
1086      manifest_signature_size != -1 &&
1087      system_state_->prefs()->GetInt64(kPrefsUpdateStateNextDataOffset,
1088                                       &next_data_offset) &&
1089      next_data_offset != -1 &&
1090      system_state_->prefs()->GetInt64(kPrefsUpdateStateNextDataLength,
1091                                       &next_data_length)) {
1092    minimum_size = manifest_metadata_size + manifest_signature_size +
1093                   next_data_offset + next_data_length;
1094  }
1095
1096  string file_id = utils::CalculateP2PFileId(response.hash, response.size);
1097  if (system_state_->p2p_manager()) {
1098    LOG(INFO) << "Checking if payload is available via p2p, file_id="
1099              << file_id << " minimum_size=" << minimum_size;
1100    system_state_->p2p_manager()->LookupUrlForFile(
1101        file_id,
1102        minimum_size,
1103        TimeDelta::FromSeconds(kMaxP2PNetworkWaitTimeSeconds),
1104        base::Bind(&OmahaRequestAction::OnLookupPayloadViaP2PCompleted,
1105                   base::Unretained(this)));
1106  }
1107}
1108
1109bool OmahaRequestAction::ShouldDeferDownload(OmahaResponse* output_object) {
1110  if (params_->interactive()) {
1111    LOG(INFO) << "Not deferring download because update is interactive.";
1112    return false;
1113  }
1114
1115  // If we're using p2p to download _and_ we have a p2p URL, we never
1116  // defer the download. This is because the download will always
1117  // happen from a peer on the LAN and we've been waiting in line for
1118  // our turn.
1119  const PayloadStateInterface* payload_state = system_state_->payload_state();
1120  if (payload_state->GetUsingP2PForDownloading() &&
1121      !payload_state->GetP2PUrl().empty()) {
1122    LOG(INFO) << "Download not deferred because download "
1123              << "will happen from a local peer (via p2p).";
1124    return false;
1125  }
1126
1127  // We should defer the downloads only if we've first satisfied the
1128  // wall-clock-based-waiting period and then the update-check-based waiting
1129  // period, if required.
1130  if (!params_->wall_clock_based_wait_enabled()) {
1131    LOG(INFO) << "Wall-clock-based waiting period is not enabled,"
1132              << " so no deferring needed.";
1133    return false;
1134  }
1135
1136  switch (IsWallClockBasedWaitingSatisfied(output_object)) {
1137    case kWallClockWaitNotSatisfied:
1138      // We haven't even satisfied the first condition, passing the
1139      // wall-clock-based waiting period, so we should defer the downloads
1140      // until that happens.
1141      LOG(INFO) << "wall-clock-based-wait not satisfied.";
1142      return true;
1143
1144    case kWallClockWaitDoneButUpdateCheckWaitRequired:
1145      LOG(INFO) << "wall-clock-based-wait satisfied and "
1146                << "update-check-based-wait required.";
1147      return !IsUpdateCheckCountBasedWaitingSatisfied();
1148
1149    case kWallClockWaitDoneAndUpdateCheckWaitNotRequired:
1150      // Wall-clock-based waiting period is satisfied, and it's determined
1151      // that we do not need the update-check-based wait. so no need to
1152      // defer downloads.
1153      LOG(INFO) << "wall-clock-based-wait satisfied and "
1154                << "update-check-based-wait is not required.";
1155      return false;
1156
1157    default:
1158      // Returning false for this default case so we err on the
1159      // side of downloading updates than deferring in case of any bugs.
1160      NOTREACHED();
1161      return false;
1162  }
1163}
1164
1165OmahaRequestAction::WallClockWaitResult
1166OmahaRequestAction::IsWallClockBasedWaitingSatisfied(
1167    OmahaResponse* output_object) {
1168  Time update_first_seen_at;
1169  int64_t update_first_seen_at_int;
1170
1171  if (system_state_->prefs()->Exists(kPrefsUpdateFirstSeenAt)) {
1172    if (system_state_->prefs()->GetInt64(kPrefsUpdateFirstSeenAt,
1173                                         &update_first_seen_at_int)) {
1174      // Note: This timestamp could be that of ANY update we saw in the past
1175      // (not necessarily this particular update we're considering to apply)
1176      // but never got to apply because of some reason (e.g. stop AU policy,
1177      // updates being pulled out from Omaha, changes in target version prefix,
1178      // new update being rolled out, etc.). But for the purposes of scattering
1179      // it doesn't matter which update the timestamp corresponds to. i.e.
1180      // the clock starts ticking the first time we see an update and we're
1181      // ready to apply when the random wait period is satisfied relative to
1182      // that first seen timestamp.
1183      update_first_seen_at = Time::FromInternalValue(update_first_seen_at_int);
1184      LOG(INFO) << "Using persisted value of UpdateFirstSeenAt: "
1185                << utils::ToString(update_first_seen_at);
1186    } else {
1187      // This seems like an unexpected error where the persisted value exists
1188      // but it's not readable for some reason. Just skip scattering in this
1189      // case to be safe.
1190     LOG(INFO) << "Not scattering as UpdateFirstSeenAt value cannot be read";
1191     return kWallClockWaitDoneAndUpdateCheckWaitNotRequired;
1192    }
1193  } else {
1194    update_first_seen_at = Time::Now();
1195    update_first_seen_at_int = update_first_seen_at.ToInternalValue();
1196    if (system_state_->prefs()->SetInt64(kPrefsUpdateFirstSeenAt,
1197                                         update_first_seen_at_int)) {
1198      LOG(INFO) << "Persisted the new value for UpdateFirstSeenAt: "
1199                << utils::ToString(update_first_seen_at);
1200    } else {
1201      // This seems like an unexpected error where the value cannot be
1202      // persisted for some reason. Just skip scattering in this
1203      // case to be safe.
1204      LOG(INFO) << "Not scattering as UpdateFirstSeenAt value "
1205                << utils::ToString(update_first_seen_at)
1206                << " cannot be persisted";
1207     return kWallClockWaitDoneAndUpdateCheckWaitNotRequired;
1208    }
1209  }
1210
1211  TimeDelta elapsed_time = Time::Now() - update_first_seen_at;
1212  TimeDelta max_scatter_period = TimeDelta::FromDays(
1213      output_object->max_days_to_scatter);
1214
1215  LOG(INFO) << "Waiting Period = "
1216            << utils::FormatSecs(params_->waiting_period().InSeconds())
1217            << ", Time Elapsed = "
1218            << utils::FormatSecs(elapsed_time.InSeconds())
1219            << ", MaxDaysToScatter = "
1220            << max_scatter_period.InDays();
1221
1222  if (!output_object->deadline.empty()) {
1223    // The deadline is set for all rules which serve a delta update from a
1224    // previous FSI, which means this update will be applied mostly in OOBE
1225    // cases. For these cases, we shouldn't scatter so as to finish the OOBE
1226    // quickly.
1227    LOG(INFO) << "Not scattering as deadline flag is set";
1228    return kWallClockWaitDoneAndUpdateCheckWaitNotRequired;
1229  }
1230
1231  if (max_scatter_period.InDays() == 0) {
1232    // This means the Omaha rule creator decides that this rule
1233    // should not be scattered irrespective of the policy.
1234    LOG(INFO) << "Not scattering as MaxDaysToScatter in rule is 0.";
1235    return kWallClockWaitDoneAndUpdateCheckWaitNotRequired;
1236  }
1237
1238  if (elapsed_time > max_scatter_period) {
1239    // This means we've waited more than the upperbound wait in the rule
1240    // from the time we first saw a valid update available to us.
1241    // This will prevent update starvation.
1242    LOG(INFO) << "Not scattering as we're past the MaxDaysToScatter limit.";
1243    return kWallClockWaitDoneAndUpdateCheckWaitNotRequired;
1244  }
1245
1246  // This means we are required to participate in scattering.
1247  // See if our turn has arrived now.
1248  TimeDelta remaining_wait_time = params_->waiting_period() - elapsed_time;
1249  if (remaining_wait_time.InSeconds() <= 0) {
1250    // Yes, it's our turn now.
1251    LOG(INFO) << "Successfully passed the wall-clock-based-wait.";
1252
1253    // But we can't download until the update-check-count-based wait is also
1254    // satisfied, so mark it as required now if update checks are enabled.
1255    return params_->update_check_count_wait_enabled() ?
1256              kWallClockWaitDoneButUpdateCheckWaitRequired :
1257              kWallClockWaitDoneAndUpdateCheckWaitNotRequired;
1258  }
1259
1260  // Not our turn yet, so we have to wait until our turn to
1261  // help scatter the downloads across all clients of the enterprise.
1262  LOG(INFO) << "Update deferred for another "
1263            << utils::FormatSecs(remaining_wait_time.InSeconds())
1264            << " per policy.";
1265  return kWallClockWaitNotSatisfied;
1266}
1267
1268bool OmahaRequestAction::IsUpdateCheckCountBasedWaitingSatisfied() {
1269  int64_t update_check_count_value;
1270
1271  if (system_state_->prefs()->Exists(kPrefsUpdateCheckCount)) {
1272    if (!system_state_->prefs()->GetInt64(kPrefsUpdateCheckCount,
1273                                          &update_check_count_value)) {
1274      // We are unable to read the update check count from file for some reason.
1275      // So let's proceed anyway so as to not stall the update.
1276      LOG(ERROR) << "Unable to read update check count. "
1277                 << "Skipping update-check-count-based-wait.";
1278      return true;
1279    }
1280  } else {
1281    // This file does not exist. This means we haven't started our update
1282    // check count down yet, so this is the right time to start the count down.
1283    update_check_count_value = base::RandInt(
1284      params_->min_update_checks_needed(),
1285      params_->max_update_checks_allowed());
1286
1287    LOG(INFO) << "Randomly picked update check count value = "
1288              << update_check_count_value;
1289
1290    // Write out the initial value of update_check_count_value.
1291    if (!system_state_->prefs()->SetInt64(kPrefsUpdateCheckCount,
1292                                          update_check_count_value)) {
1293      // We weren't able to write the update check count file for some reason.
1294      // So let's proceed anyway so as to not stall the update.
1295      LOG(ERROR) << "Unable to write update check count. "
1296                 << "Skipping update-check-count-based-wait.";
1297      return true;
1298    }
1299  }
1300
1301  if (update_check_count_value == 0) {
1302    LOG(INFO) << "Successfully passed the update-check-based-wait.";
1303    return true;
1304  }
1305
1306  if (update_check_count_value < 0 ||
1307      update_check_count_value > params_->max_update_checks_allowed()) {
1308    // We err on the side of skipping scattering logic instead of stalling
1309    // a machine from receiving any updates in case of any unexpected state.
1310    LOG(ERROR) << "Invalid value for update check count detected. "
1311               << "Skipping update-check-count-based-wait.";
1312    return true;
1313  }
1314
1315  // Legal value, we need to wait for more update checks to happen
1316  // until this becomes 0.
1317  LOG(INFO) << "Deferring Omaha updates for another "
1318            << update_check_count_value
1319            << " update checks per policy";
1320  return false;
1321}
1322
1323// static
1324bool OmahaRequestAction::ParseInstallDate(OmahaParserData* parser_data,
1325                                          OmahaResponse* output_object) {
1326  int64_t elapsed_days = 0;
1327  if (!base::StringToInt64(parser_data->daystart_elapsed_days,
1328                           &elapsed_days))
1329    return false;
1330
1331  if (elapsed_days < 0)
1332    return false;
1333
1334  output_object->install_date_days = elapsed_days;
1335  return true;
1336}
1337
1338// static
1339bool OmahaRequestAction::HasInstallDate(SystemState *system_state) {
1340  PrefsInterface* prefs = system_state->prefs();
1341  if (prefs == nullptr)
1342    return false;
1343
1344  return prefs->Exists(kPrefsInstallDateDays);
1345}
1346
1347// static
1348bool OmahaRequestAction::PersistInstallDate(
1349    SystemState *system_state,
1350    int install_date_days,
1351    InstallDateProvisioningSource source) {
1352  TEST_AND_RETURN_FALSE(install_date_days >= 0);
1353
1354  PrefsInterface* prefs = system_state->prefs();
1355  if (prefs == nullptr)
1356    return false;
1357
1358  if (!prefs->SetInt64(kPrefsInstallDateDays, install_date_days))
1359    return false;
1360
1361  string metric_name = metrics::kMetricInstallDateProvisioningSource;
1362  system_state->metrics_lib()->SendEnumToUMA(
1363      metric_name,
1364      static_cast<int>(source),  // Sample.
1365      kProvisionedMax);          // Maximum.
1366
1367  return true;
1368}
1369
1370bool OmahaRequestAction::PersistCohortData(
1371    const string& prefs_key,
1372    const string& new_value) {
1373  if (new_value.empty() && system_state_->prefs()->Exists(prefs_key)) {
1374    LOG(INFO) << "Removing stored " << prefs_key << " value.";
1375    return system_state_->prefs()->Delete(prefs_key);
1376  } else if (!new_value.empty()) {
1377    LOG(INFO) << "Storing new setting " << prefs_key << " as " << new_value;
1378    return system_state_->prefs()->SetString(prefs_key, new_value);
1379  }
1380  return true;
1381}
1382
1383void OmahaRequestAction::ActionCompleted(ErrorCode code) {
1384  // We only want to report this on "update check".
1385  if (ping_only_ || event_ != nullptr)
1386    return;
1387
1388  metrics::CheckResult result = metrics::CheckResult::kUnset;
1389  metrics::CheckReaction reaction = metrics::CheckReaction::kUnset;
1390  metrics::DownloadErrorCode download_error_code =
1391      metrics::DownloadErrorCode::kUnset;
1392
1393  // Regular update attempt.
1394  switch (code) {
1395  case ErrorCode::kSuccess:
1396    // OK, we parsed the response successfully but that does
1397    // necessarily mean that an update is available.
1398    if (HasOutputPipe()) {
1399      const OmahaResponse& response = GetOutputObject();
1400      if (response.update_exists) {
1401        result = metrics::CheckResult::kUpdateAvailable;
1402        reaction = metrics::CheckReaction::kUpdating;
1403      } else {
1404        result = metrics::CheckResult::kNoUpdateAvailable;
1405      }
1406    } else {
1407      result = metrics::CheckResult::kNoUpdateAvailable;
1408    }
1409    break;
1410
1411  case ErrorCode::kOmahaUpdateIgnoredPerPolicy:
1412    result = metrics::CheckResult::kUpdateAvailable;
1413    reaction = metrics::CheckReaction::kIgnored;
1414    break;
1415
1416  case ErrorCode::kOmahaUpdateDeferredPerPolicy:
1417    result = metrics::CheckResult::kUpdateAvailable;
1418    reaction = metrics::CheckReaction::kDeferring;
1419    break;
1420
1421  case ErrorCode::kOmahaUpdateDeferredForBackoff:
1422    result = metrics::CheckResult::kUpdateAvailable;
1423    reaction = metrics::CheckReaction::kBackingOff;
1424    break;
1425
1426  default:
1427    // We report two flavors of errors, "Download errors" and "Parsing
1428    // error". Try to convert to the former and if that doesn't work
1429    // we know it's the latter.
1430    metrics::DownloadErrorCode tmp_error =
1431        metrics_utils::GetDownloadErrorCode(code);
1432    if (tmp_error != metrics::DownloadErrorCode::kInputMalformed) {
1433      result = metrics::CheckResult::kDownloadError;
1434      download_error_code = tmp_error;
1435    } else {
1436      result = metrics::CheckResult::kParsingError;
1437    }
1438    break;
1439  }
1440
1441  metrics::ReportUpdateCheckMetrics(system_state_,
1442                                    result, reaction, download_error_code);
1443}
1444
1445bool OmahaRequestAction::ShouldIgnoreUpdate(
1446    const OmahaResponse& response) const {
1447  // Note: policy decision to not update to a version we rolled back from.
1448  string rollback_version =
1449      system_state_->payload_state()->GetRollbackVersion();
1450  if (!rollback_version.empty()) {
1451    LOG(INFO) << "Detected previous rollback from version " << rollback_version;
1452    if (rollback_version == response.version) {
1453      LOG(INFO) << "Received version that we rolled back from. Ignoring.";
1454      return true;
1455    }
1456  }
1457
1458  if (!IsUpdateAllowedOverCurrentConnection()) {
1459    LOG(INFO) << "Update is not allowed over current connection.";
1460    return true;
1461  }
1462
1463  // Note: We could technically delete the UpdateFirstSeenAt state when we
1464  // return true. If we do, it'll mean a device has to restart the
1465  // UpdateFirstSeenAt and thus help scattering take effect when the AU is
1466  // turned on again. On the other hand, it also increases the chance of update
1467  // starvation if an admin turns AU on/off more frequently. We choose to err on
1468  // the side of preventing starvation at the cost of not applying scattering in
1469  // those cases.
1470  return false;
1471}
1472
1473bool OmahaRequestAction::IsUpdateAllowedOverCurrentConnection() const {
1474  NetworkConnectionType type;
1475  NetworkTethering tethering;
1476  ConnectionManagerInterface* connection_manager =
1477      system_state_->connection_manager();
1478  if (!connection_manager->GetConnectionProperties(&type, &tethering)) {
1479    LOG(INFO) << "We could not determine our connection type. "
1480              << "Defaulting to allow updates.";
1481    return true;
1482  }
1483  bool is_allowed = connection_manager->IsUpdateAllowedOver(type, tethering);
1484  LOG(INFO) << "We are connected via "
1485            << ConnectionManager::StringForConnectionType(type)
1486            << ", Updates allowed: " << (is_allowed ? "Yes" : "No");
1487  return is_allowed;
1488}
1489
1490}  // namespace chromeos_update_engine
1491