Steps To Reproduce:
(1)Install any beta version(51-8172.28.0)
(2)Change channel to stable at about chrome page.
(3)Powerwash the device after successful production version update(M50)
(4)Try to auto update the latest stable from the Omaha.

Expected Result:
Should update to latest omaha version.

Actual Result:
Seen error "HTTP response unsuccessful (404) or no payload (49)"


log:

[0512/144630:INFO:certificate_checker.cc(185)] Certificate changed from 705D8A658E6FA567A1D69E35B12848A3BD45BFD0BB776C6D7AA763DB7C6552E9 to C626E4E6EADC86EA7E43C8E5D36880CC289F6CBE231085600940142D8299403D.
[0512/144630:INFO:metrics.cc(517)] Uploading 1 for metric UpdateEngine.CertificateCheck.UpdateCheck
[0512/144630:INFO:libcurl_http_fetcher.cc(427)] HTTP response unsuccessful (404) or no payload (49), nothing to do
[0512/144630:INFO:libcurl_http_fetcher.cc(331)] HTTP response code: 404
[0512/144630:INFO:libcurl_http_fetcher.cc(368)] Transfer resulted in an error (404), 0 bytes downloaded
[0512/144630:INFO:libcurl_http_fetcher.cc(382)] No further proxies, indicating transfer complete
[0512/144630:INFO:omaha_request_action.cc(900)] Omaha request response: 
[0512/144630:ERROR:omaha_request_action.cc(917)] Omaha request network transfer failed.
[0512/144630:INFO:metrics.cc(142)] Sending 2 for metric UpdateEngine.Check.Result (enum)
[0512/144630:INFO:metrics.cc(155)] Sending 404 for metric UpdateEngine.Check.DownloadErrorCode (sparse)
[0512/144630:INFO:metrics.cc(165)] Sending 24.845093s for metric UpdateEngine.Check.TimeSinceLastCheckMinutes
[0512/144630:INFO:metrics.cc(181)] Sending 24.845185s for metric UpdateEngine.Check.TimeSinceLastCheckUptimeMinutes
[0512/144630:INFO:action_processor.cc(81)] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing.
[0512/144630:INFO:action_processor.cc(86)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
[0512/144630:INFO:update_attempter.cc(913)] Processing Done.
[0512/144630:ERROR:update_attempter.cc(1328)] Update failed.
[0512/144630:INFO:utils.cc(988)] Converting error code 2404 to ErrorCode::kOmahaErrorInHTTPResponse
[0512/144630:INFO:payload_state.cc(247)] Updating payload state for error code: 37 (ErrorCode::kOmahaErrorInHTTPResponse)
[0512/144630:INFO:payload_state.cc(254)] Ignoring failures until we get a valid Omaha response.
[0512/144630:INFO:update_attempter.cc(1332)] Reporting the error event
[0512/144630:INFO:action_processor.cc(48)] ActionProcessor::StartProcessing: OmahaRequestAction
[0512/144630:INFO:omaha_request_action.cc(643)] Posting an Omaha request to https://omaha.sandbox.google.com/service/update2
[0512/144630:INFO:omaha_request_action.cc(644)] Request: <?xml version="1.0" encoding="UTF-8"?>
<request protocol="3.0" version="ChromeOSUpdateEngine-0.1.0.0" updaterversion="ChromeOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">
    <os version="Indy" platform="Chrome OS" sp="7978.66.3_x86_64"></os>
    <app appid="{40C6BCAB-B8D4-466F-9C3C-069B8CAC36D7}" version="7978.66.3" track="stable-channel" lang="en-US" board="lulu-signed-mpkeys" hardware_class="LULU D25-H2A-D6W-Q52" delta_okay="true" fw_version="" ec_version="" installdate="3416" >
        <event eventtype="3" eventresult="0" errorcode="-1879045788"></event>
    </app>
</request>

[0512/144630:INFO:libcurl_http_fetcher.cc(94)] Starting/Resuming transfer
[0512/144630:INFO:libcurl_http_fetcher.cc(105)] Using proxy: no
[0512/144630:INFO:libcurl_http_fetcher.cc(240)] Setting up curl options for HTTPS
[0512/144630:INFO:libcurl_http_fetcher.cc(540)] Setting up timeout source: 1 seconds.
[0512/144630:INFO:libcurl_http_fetcher.cc(427)] HTTP response unsuccessful (404) or no payload (49), nothing to do
[0512/144630:INFO:libcurl_http_fetcher.cc(331)] HTTP response code: 404
[0512/144630:INFO:libcurl_http_fetcher.cc(368)] Transfer resulted in an error (404), 0 bytes downloaded
[0512/144630:INFO:libcurl_http_fetcher.cc(382)] No further proxies, indicating transfer complete
[0512/144630:INFO:omaha_request_action.cc(900)] Omaha request response: 
[0512/144630:INFO:action_processor.cc(78)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
[0512/144630:INFO:action_processor.cc(86)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
[0512/144630:INFO:update_attempter.cc(913)] Processing Done.
[0512/144630:INFO:update_attempter.cc(920)] Error event sent.


How frequently does this problem reproduce? (Always, sometimes, hard to
reproduce?)

What is the impact to the user, and is there a workaround? If so, what is
it?

Please provide any additional information below. Attach a screen shot or
log if possible.


 

Deleted: update_engine.20160512-144547.txt 20.2 KB

update_engine.20160512-144547.txt 20.2 KB View Download