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("""); 471 break; 472 case '\'': 473 output->append("'"); 474 break; 475 case '&': 476 output->append("&"); 477 break; 478 case '<': 479 output->append("<"); 480 break; 481 case '>': 482 output->append(">"); 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