How to manually update CoreOS to latest version?

3,010 views
Skip to first unread message

she...@36kr.com

unread,
May 6, 2015, 10:28:33 PM5/6/15
to coreo...@googlegroups.com
Dear all,

   I use "sudo update_engine_client -update" for upgrading CoreOS, but it always fails with following logs. I think it is due to very low download speed. Can I manually wget update.gz from official site, put it into some specified directory and then restart CoreOS to enforce upgrade?

[0507/102324:INFO:update_engine_client.cc(245)] Initiating update check and install.
[0507/102324:INFO:update_engine_client.cc(250)] Waiting for update to complete.
LAST_CHECKED_TIME=1430964700
PROGRESS=0.000000
CURRENT_OP=UPDATE_STATUS_CHECKING_FOR_UPDATE
NEW_VERSION=0.0.0.0
NEW_SIZE=129140021
LAST_CHECKED_TIME=1430965412
PROGRESS=0.000000
CURRENT_OP=UPDATE_STATUS_UPDATE_AVAILABLE
NEW_VERSION=0.0.0.0
NEW_SIZE=129140021
LAST_CHECKED_TIME=1430965412
PROGRESS=0.000000
CURRENT_OP=UPDATE_STATUS_UPDATE_AVAILABLE
NEW_VERSION=0.0.0.0
NEW_SIZE=129140021
LAST_CHECKED_TIME=1430965412
PROGRESS=0.000000
CURRENT_OP=UPDATE_STATUS_REPORTING_ERROR_EVENT
NEW_VERSION=0.0.0.0
NEW_SIZE=129140021
LAST_CHECKED_TIME=1430965412
PROGRESS=0.000000
CURRENT_OP=UPDATE_STATUS_IDLE
NEW_VERSION=0.0.0.0
NEW_SIZE=129140021
[0507/102415:ERROR:update_engine_client.cc(189)] Update failed.
core@dev11 ~ $ journalctl -f -u update-engine
-- Logs begin at Thu 2015-04-02 13:42:56 CST. --
May 07 10:24:12 dev11 update_engine[484]: </actions>
May 07 10:24:12 dev11 update_engine[484]: </manifest>
May 07 10:24:12 dev11 update_engine[484]: </updatecheck>
May 07 10:24:12 dev11 update_engine[484]: </app>
May 07 10:24:12 dev11 update_engine[484]: </response>
May 07 10:24:12 dev11 update_engine[484]: [0507/102412:ERROR:omaha_request_action.cc(595)] HTTP reported success but Omaha reports an error.
May 07 10:24:12 dev11 update_engine[484]: [0507/102412:INFO:action_processor.cc(65)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 07 10:24:12 dev11 update_engine[484]: [0507/102412:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 07 10:24:12 dev11 update_engine[484]: [0507/102412:INFO:update_attempter.cc(460)] Processing Done.
May 07 10:24:12 dev11 update_engine[484]: [0507/102412:INFO:update_attempter.cc(467)] Error event sent.


Michael Marineau

unread,
May 7, 2015, 12:53:41 AM5/7/15
to she...@36kr.com, coreos-user
No, update_engine only understands fetching the update payload itself.
Could you post the complete logs so we can see what the actual error
was? Something like:

journalctl -u update-engine --since '2015-05-07 10:23:24' --until
'2015-05-07 10:24:12' --full
> --
> You received this message because you are subscribed to the Google Groups
> "CoreOS User" group.
> To unsubscribe from this group and stop receiving emails from it, send an
> email to coreos-user...@googlegroups.com.
> For more options, visit https://groups.google.com/d/optout.

沈雷

unread,
May 7, 2015, 2:23:10 AM5/7/15
to Michael Marineau, coreos-user
Suppose you mean update_engine logs.


-- Reboot --
May 07 10:33:49 dev11 systemd[1]: Starting Update Engine...
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:main.cc(159)] CoreOS Update Engine starting
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:payload_state.cc(356)] Current Response Signature =
May 07 10:33:49 dev11 update_engine[489]: NumURLs = 1
May 07 10:33:49 dev11 update_engine[489]: Payload Size = 129140021
May 07 10:33:49 dev11 update_engine[489]: Payload Sha256 Hash = B2RprH+KhBfSKARR7Ezd5cTqgFDbZCeQaBACg62yK1I=
May 07 10:33:49 dev11 update_engine[489]: Is Delta Payload = 0
May 07 10:33:49 dev11 update_engine[489]: Max Failure Count Per Url = 10
May 07 10:33:49 dev11 update_engine[489]: Disable Payload Backoff = 1
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:payload_state.cc(377)] Payload Attempt Number = 10
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:payload_state.cc(400)] Current URL Index = 0
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:payload_state.cc(421)] Current URL (Url0)'s Failure Count = 6
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:payload_state.cc(448)] Backoff Expiry Time = 1/1/1601 0:00:00 GMT
May 07 10:33:49 dev11 systemd[1]: Started Update Engine.
May 07 10:33:49 dev11 update_engine[489]: [0507/103349:INFO:update_check_scheduler.cc(82)] Next update check in 2m38s
May 07 10:34:34 dev11 update_engine[489]: [0507/103434:INFO:update_attempter.cc(674)] Updating boot flags...
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:prefs.cc(54)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:prefs.cc(54)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:WARNING:libpolicy.cc(29)] Could not load the device policy file.
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:update_attempter.cc(195)] No device policies/settings present.
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:update_attempter.cc(239)] Scattering disabled since scatter factor is set to 0
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:omaha_request_params.cc(61)] Current group set to stable
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:update_attempter.cc(206)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:update_attempter.cc(213)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:update_attempter.cc(664)] Already updated boot flags. Skipping.
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:update_attempter.cc(847)] Scheduling an action processor start.
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 10:36:27 dev11 update_engine[489]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1">
May 07 10:36:27 dev11 update_engine[489]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 10:36:27 dev11 update_engine[489]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{e238a010-2155-4844-b9f8-da32c9b97336}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 10:36:27 dev11 update_engine[489]: <ping active="1"></ping>
May 07 10:36:27 dev11 update_engine[489]: <updatecheck targetversionprefix=""></updatecheck>
May 07 10:36:27 dev11 update_engine[489]: <event eventtype="3" eventresult="2" previousversion=""></event>
May 07 10:36:27 dev11 update_engine[489]: </app>
May 07 10:36:27 dev11 update_engine[489]: </request>
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 10:36:27 dev11 update_engine[489]: [0507/103627:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 267 bytes downloaded
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 10:36:29 dev11 update_engine[489]: <response protocol="3.0" server="update.core-os.net">
May 07 10:36:29 dev11 update_engine[489]: <daystart elapsed_seconds="0"></daystart>
May 07 10:36:29 dev11 update_engine[489]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 10:36:29 dev11 update_engine[489]: <updatecheck status="noupdate"></updatecheck>
May 07 10:36:29 dev11 update_engine[489]: </app>
May 07 10:36:29 dev11 update_engine[489]: </response>
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:omaha_request_action.cc(406)] No update.
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting.
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing.
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:update_attempter.cc(460)] Processing Done.
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:update_attempter.cc(508)] No update.
May 07 10:36:29 dev11 update_engine[489]: [0507/103629:INFO:update_check_scheduler.cc(82)] Next update check in 44m3s
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:prefs.cc(54)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:prefs.cc(54)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:WARNING:libpolicy.cc(29)] Could not load the device policy file.
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:update_attempter.cc(195)] No device policies/settings present.
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:update_attempter.cc(239)] Scattering disabled since scatter factor is set to 0
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:omaha_request_params.cc(61)] Current group set to stable
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:update_attempter.cc(206)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:update_attempter.cc(213)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:update_attempter.cc(664)] Already updated boot flags. Skipping.
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:update_attempter.cc(847)] Scheduling an action processor start.
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 11:20:32 dev11 update_engine[489]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1">
May 07 11:20:32 dev11 update_engine[489]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 11:20:32 dev11 update_engine[489]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{e238a010-2155-4844-b9f8-da32c9b97336}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 11:20:32 dev11 update_engine[489]: <ping active="1"></ping>
May 07 11:20:32 dev11 update_engine[489]: <updatecheck targetversionprefix=""></updatecheck>
May 07 11:20:32 dev11 update_engine[489]: <event eventtype="3" eventresult="2" previousversion=""></event>
May 07 11:20:32 dev11 update_engine[489]: </app>
May 07 11:20:32 dev11 update_engine[489]: </request>
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 11:20:32 dev11 update_engine[489]: [0507/112032:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 267 bytes downloaded
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 11:20:33 dev11 update_engine[489]: <response protocol="3.0" server="update.core-os.net">
May 07 11:20:33 dev11 update_engine[489]: <daystart elapsed_seconds="0"></daystart>
May 07 11:20:33 dev11 update_engine[489]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 11:20:33 dev11 update_engine[489]: <updatecheck status="noupdate"></updatecheck>
May 07 11:20:33 dev11 update_engine[489]: </app>
May 07 11:20:33 dev11 update_engine[489]: </response>
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:omaha_request_action.cc(406)] No update.
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting.
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing.
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:update_attempter.cc(460)] Processing Done.
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:update_attempter.cc(508)] No update.
May 07 11:20:33 dev11 update_engine[489]: [0507/112033:INFO:update_check_scheduler.cc(82)] Next update check in 42m30s
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:prefs.cc(54)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:prefs.cc(54)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:WARNING:libpolicy.cc(29)] Could not load the device policy file.
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:update_attempter.cc(195)] No device policies/settings present.
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:update_attempter.cc(239)] Scattering disabled since scatter factor is set to 0
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:omaha_request_params.cc(61)] Current group set to stable
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:update_attempter.cc(206)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:update_attempter.cc(213)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:update_attempter.cc(664)] Already updated boot flags. Skipping.
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:update_attempter.cc(847)] Scheduling an action processor start.
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 12:03:03 dev11 update_engine[489]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1">
May 07 12:03:03 dev11 update_engine[489]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 12:03:03 dev11 update_engine[489]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{e238a010-2155-4844-b9f8-da32c9b97336}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 12:03:03 dev11 update_engine[489]: <ping active="1"></ping>
May 07 12:03:03 dev11 update_engine[489]: <updatecheck targetversionprefix=""></updatecheck>
May 07 12:03:03 dev11 update_engine[489]: <event eventtype="3" eventresult="2" previousversion=""></event>
May 07 12:03:03 dev11 update_engine[489]: </app>
May 07 12:03:03 dev11 update_engine[489]: </request>
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 12:03:03 dev11 update_engine[489]: [0507/120303:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 267 bytes downloaded
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 12:03:05 dev11 update_engine[489]: <response protocol="3.0" server="update.core-os.net">
May 07 12:03:05 dev11 update_engine[489]: <daystart elapsed_seconds="0"></daystart>
May 07 12:03:05 dev11 update_engine[489]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 12:03:05 dev11 update_engine[489]: <updatecheck status="noupdate"></updatecheck>
May 07 12:03:05 dev11 update_engine[489]: </app>
May 07 12:03:05 dev11 update_engine[489]: </response>
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:omaha_request_action.cc(406)] No update.
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting.
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing.
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:update_attempter.cc(460)] Processing Done.
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:update_attempter.cc(508)] No update.
May 07 12:03:05 dev11 update_engine[489]: [0507/120305:INFO:update_check_scheduler.cc(82)] Next update check in 43m23s
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:prefs.cc(54)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:prefs.cc(54)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:WARNING:libpolicy.cc(29)] Could not load the device policy file.
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:update_attempter.cc(195)] No device policies/settings present.
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:update_attempter.cc(239)] Scattering disabled since scatter factor is set to 0
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:omaha_request_params.cc(61)] Current group set to stable
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:update_attempter.cc(206)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:update_attempter.cc(213)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:update_attempter.cc(664)] Already updated boot flags. Skipping.
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:update_attempter.cc(847)] Scheduling an action processor start.
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 12:46:28 dev11 update_engine[489]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1">
May 07 12:46:28 dev11 update_engine[489]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 12:46:28 dev11 update_engine[489]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{e238a010-2155-4844-b9f8-da32c9b97336}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 12:46:28 dev11 update_engine[489]: <ping active="1"></ping>
May 07 12:46:28 dev11 update_engine[489]: <updatecheck targetversionprefix=""></updatecheck>
May 07 12:46:28 dev11 update_engine[489]: <event eventtype="3" eventresult="2" previousversion=""></event>
May 07 12:46:28 dev11 update_engine[489]: </app>
May 07 12:46:28 dev11 update_engine[489]: </request>
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 12:46:28 dev11 update_engine[489]: [0507/124628:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 267 bytes downloaded
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 12:46:30 dev11 update_engine[489]: <response protocol="3.0" server="update.core-os.net">
May 07 12:46:30 dev11 update_engine[489]: <daystart elapsed_seconds="0"></daystart>
May 07 12:46:30 dev11 update_engine[489]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 12:46:30 dev11 update_engine[489]: <updatecheck status="noupdate"></updatecheck>
May 07 12:46:30 dev11 update_engine[489]: </app>
May 07 12:46:30 dev11 update_engine[489]: </response>
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:omaha_request_action.cc(406)] No update.
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting.
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing.
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:update_attempter.cc(460)] Processing Done.
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:update_attempter.cc(508)] No update.
May 07 12:46:30 dev11 update_engine[489]: [0507/124630:INFO:update_check_scheduler.cc(82)] Next update check in 48m10s
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:prefs.cc(54)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:prefs.cc(54)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:WARNING:libpolicy.cc(29)] Could not load the device policy file.
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:update_attempter.cc(195)] No device policies/settings present.
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:update_attempter.cc(239)] Scattering disabled since scatter factor is set to 0
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:omaha_request_params.cc(61)] Current group set to stable
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:update_attempter.cc(206)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:update_attempter.cc(213)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:update_attempter.cc(664)] Already updated boot flags. Skipping.
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:update_attempter.cc(847)] Scheduling an action processor start.
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 13:34:40 dev11 update_engine[489]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1">
May 07 13:34:40 dev11 update_engine[489]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 13:34:40 dev11 update_engine[489]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{e238a010-2155-4844-b9f8-da32c9b97336}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 13:34:40 dev11 update_engine[489]: <ping active="1"></ping>
May 07 13:34:40 dev11 update_engine[489]: <updatecheck targetversionprefix=""></updatecheck>
May 07 13:34:40 dev11 update_engine[489]: <event eventtype="3" eventresult="2" previousversion=""></event>
May 07 13:34:40 dev11 update_engine[489]: </app>
May 07 13:34:40 dev11 update_engine[489]: </request>
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 13:34:40 dev11 update_engine[489]: [0507/133440:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 267 bytes downloaded
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 13:34:43 dev11 update_engine[489]: <response protocol="3.0" server="update.core-os.net">
May 07 13:34:43 dev11 update_engine[489]: <daystart elapsed_seconds="0"></daystart>
May 07 13:34:43 dev11 update_engine[489]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 13:34:43 dev11 update_engine[489]: <updatecheck status="noupdate"></updatecheck>
May 07 13:34:43 dev11 update_engine[489]: </app>
May 07 13:34:43 dev11 update_engine[489]: </response>
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:omaha_request_action.cc(406)] No update.
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting.
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing.
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:update_attempter.cc(460)] Processing Done.
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:update_attempter.cc(508)] No update.
May 07 13:34:43 dev11 update_engine[489]: [0507/133443:INFO:update_check_scheduler.cc(82)] Next update check in 45m18s

--
沈雷 - 技术
QQ：22232203
手机：15901016832
地址：北京市海淀区海淀西大街39号3层36氪

Michael Marineau

unread,
May 7, 2015, 3:31:13 AM5/7/15
to 沈雷, coreos-user
The logs you posted do not include the time during your failed
`update_engine_client -update` run and by luck the automatic checks
were hitting the server's rate limit so they don't include an update
attempt. Running `update_engine_client -update` bypasses that rate
limiting. Could you post the logs reported by the journalctl command I
posted earlier please?

journalctl -u update-engine \
--since '2015-05-07 10:23:24' \
--until '2015-05-07 10:24:12' --full

沈雷

unread,
May 7, 2015, 3:49:52 AM5/7/15
to Michael Marineau, coreos-user
Firstly, thank you very much for the help.
I have run `update_engine_client -update` again. Below text is related logs. 

It seems a network error. FYI, my server is in China. The GreatFireWall will block connections to google. If so, I should use proxy or copy update.gz to magic directory. Anyway, it will be very tricky T_T

--
ShenLei


May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:dbus_service.cc(66)] Attempting interactive update
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(433)] New update check requested
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:WARNING:libpolicy.cc(29)] Could not load the device policy file.
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(195)] No device policies/settings present.
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(239)] Scattering disabled since scatter factor is set to 0
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:omaha_request_params.cc(61)] Current group set to stable
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(206)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(213)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(664)] Already updated boot flags. Skipping.
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:update_attempter.cc(847)] Scheduling an action processor start.
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 15:43:01 dev11 update_engine[487]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">
May 07 15:43:01 dev11 update_engine[487]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 15:43:01 dev11 update_engine[487]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{ddbf34a8-d4f0-4925-8c61-fe91ae460f5f}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 15:43:01 dev11 update_engine[487]: <ping active="1"></ping>
May 07 15:43:01 dev11 update_engine[487]: <updatecheck targetversionprefix=""></updatecheck>
May 07 15:43:01 dev11 update_engine[487]: <event eventtype="3" eventresult="2" previousversion=""></event>
May 07 15:43:01 dev11 update_engine[487]: </app>
May 07 15:43:01 dev11 update_engine[487]: </request>
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 15:43:01 dev11 update_engine[487]: [0507/154301:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 798 bytes downloaded
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 15:43:02 dev11 update_engine[487]: <response protocol="3.0" server="update.core-os.net">
May 07 15:43:02 dev11 update_engine[487]: <daystart elapsed_seconds="0"></daystart>
May 07 15:43:02 dev11 update_engine[487]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 15:43:02 dev11 update_engine[487]: <updatecheck status="ok">
May 07 15:43:02 dev11 update_engine[487]: <urls>
May 07 15:43:02 dev11 update_engine[487]: <url codebase="https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/633.1.0/"></url>
May 07 15:43:02 dev11 update_engine[487]: </urls>
May 07 15:43:02 dev11 update_engine[487]: <manifest version="633.1.0">
May 07 15:43:02 dev11 update_engine[487]: <packages>
May 07 15:43:02 dev11 update_engine[487]: <package hash="RIWaOLeslMhokRhQzBkwmTZYCMk=" name="update.gz" size="129140021" required="false"></package>
May 07 15:43:02 dev11 update_engine[487]: </packages>
May 07 15:43:02 dev11 update_engine[487]: <actions>
May 07 15:43:02 dev11 update_engine[487]: <action event="postinstall" ChromeOSVersion="" sha256="B2RprH+KhBfSKARR7Ezd5cTqgFDbZCeQaBACg62yK1I=" needsadmin="false" IsDelta="false" DisablePayloadBackoff="true"></action>
May 07 15:43:02 dev11 update_engine[487]: </actions>
May 07 15:43:02 dev11 update_engine[487]: </manifest>
May 07 15:43:02 dev11 update_engine[487]: </updatecheck>
May 07 15:43:02 dev11 update_engine[487]: </app>
May 07 15:43:02 dev11 update_engine[487]: </response>
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(439)] Found 1 url(s)
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(475)] Processing first of 1 package(s)
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(482)] Omaha Response package name = update.gz
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(494)] Url0: https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/633.1.0/update.gz
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(506)] Payload size = 129140021 bytes
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_request_action.cc(530)] Found 1 action(s). Processing the postinstall action.
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:payload_state.cc(199)] Payload backoff logic is disabled. Can proceed with the download
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_response_handler_action.cc(43)] Using Url0 as the download url this time
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_response_handler_action.cc(134)] Waiving payload hash checks since Omaha response only has HTTPS URL(s)
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:ERROR:delta_performer.cc(991)] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed.
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:omaha_response_handler_action.cc(71)] Using this install plan:
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:install_plan.cc(52)] InstallPlan: , new_update, url: https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/633.1.0/update.gz, payload size: 129140021, payload hash: B2RprH+KhBfSKARR7Ezd5cTqgFDbZCeQaBACg62yK1I=, install_path: /dev/sda4, kernel_install_path: , hash_checks_mandatory: false
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:utils.cc(633)] Setting cgroup cpu shares to  2
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:ERROR:utils.cc(118)] 0 == writer.Open(path, O_WRONLY | O_CREAT | O_TRUNC, 0600) failed: No such file or directory
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:ERROR:utils.cc(638)] Failed to change cgroup cpu shares to 2 using /sys/fs/cgroup/cpu/update-engine/cpu.shares
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction
May 07 15:43:02 dev11 update_engine[487]: [0507/154302:INFO:filesystem_copier_action.cc(323)] Filesystem size: 1065345024 bytes (260094x4096).
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:filesystem_copier_action.cc(283)] Hash: CEN8lGTwU66g/LoKJXtdNu9DuWBSVoY+kEFW/Z4an5k=
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting OmahaRequestAction
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 15:43:14 dev11 update_engine[487]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">
May 07 15:43:14 dev11 update_engine[487]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 15:43:14 dev11 update_engine[487]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{ddbf34a8-d4f0-4925-8c61-fe91ae460f5f}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 15:43:14 dev11 update_engine[487]: <event eventtype="13" eventresult="1"></event>
May 07 15:43:14 dev11 update_engine[487]: </app>
May 07 15:43:14 dev11 update_engine[487]: </request>
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 15:43:14 dev11 update_engine[487]: [0507/154314:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 15:43:15 dev11 update_engine[487]: [0507/154315:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 798 bytes downloaded
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 15:43:17 dev11 update_engine[487]: <response protocol="3.0" server="update.core-os.net">
May 07 15:43:17 dev11 update_engine[487]: <daystart elapsed_seconds="0"></daystart>
May 07 15:43:17 dev11 update_engine[487]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 15:43:17 dev11 update_engine[487]: <updatecheck status="ok">
May 07 15:43:17 dev11 update_engine[487]: <urls>
May 07 15:43:17 dev11 update_engine[487]: <url codebase="https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/633.1.0/"></url>
May 07 15:43:17 dev11 update_engine[487]: </urls>
May 07 15:43:17 dev11 update_engine[487]: <manifest version="633.1.0">
May 07 15:43:17 dev11 update_engine[487]: <packages>
May 07 15:43:17 dev11 update_engine[487]: <package hash="RIWaOLeslMhokRhQzBkwmTZYCMk=" name="update.gz" size="129140021" required="false"></package>
May 07 15:43:17 dev11 update_engine[487]: </packages>
May 07 15:43:17 dev11 update_engine[487]: <actions>
May 07 15:43:17 dev11 update_engine[487]: <action event="postinstall" ChromeOSVersion="" sha256="B2RprH+KhBfSKARR7Ezd5cTqgFDbZCeQaBACg62yK1I=" needsadmin="false" IsDelta="false" DisablePayloadBackoff="true"></action>
May 07 15:43:17 dev11 update_engine[487]: </actions>
May 07 15:43:17 dev11 update_engine[487]: </manifest>
May 07 15:43:17 dev11 update_engine[487]: </updatecheck>
May 07 15:43:17 dev11 update_engine[487]: </app>
May 07 15:43:17 dev11 update_engine[487]: </response>
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting DownloadAction
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:install_plan.cc(52)] InstallPlan: , new_update, url: https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/633.1.0/update.gz, payload size: 129140021, payload hash: B2RprH+KhBfSKARR7Ezd5cTqgFDbZCeQaBACg62yK1I=, install_path: /dev/sda4, kernel_install_path: , hash_checks_mandatory: false
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:update_attempter.cc(597)] Download status: active
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:multi_range_http_fetcher.cc(29)] starting first transfer
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:multi_range_http_fetcher.cc(57)] starting transfer of range 0+?
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 15:43:17 dev11 update_engine[487]: [0507/154317:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:ERROR:libcurl_http_fetcher.cc(264)] Unable to get http response code: Failed to connect to 2404:6800:4008:c01::84: Network is unreachable
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:libcurl_http_fetcher.cc(295)] Transfer resulted in an error (0), 0 bytes downloaded
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:multi_range_http_fetcher.cc(150)] Received transfer complete.
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:multi_range_http_fetcher.cc(107)] TransferEnded w/ code 0
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:multi_range_http_fetcher.cc(141)] Done w/ all transfers
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:update_attempter.cc(597)] Download status: inactive
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: DownloadAction action failed. Aborting processing.
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type DownloadAction
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:update_attempter.cc(460)] Processing Done.
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:ERROR:update_attempter.cc(787)] Update failed.
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:payload_state.cc(97)] Updating payload state for error code: 9 (kActionCodeDownloadTransferError)
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:payload_state.cc(271)] Incrementing the URL failure count
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:payload_state.cc(421)] Current URL (Url0)'s Failure Count = 8
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:omaha_request_action.cc(265)] Posting an Omaha request to https://public.update.core-os.net/v1/update/
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:omaha_request_action.cc(266)] Request: <?xml version="1.0" encoding="UTF-8"?>
May 07 15:43:20 dev11 update_engine[487]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">
May 07 15:43:20 dev11 update_engine[487]: <os version="Chateau" platform="CoreOS" sp="607.0.0_x86_64"></os>
May 07 15:43:20 dev11 update_engine[487]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="607.0.0" track="stable" bootid="{ddbf34a8-d4f0-4925-8c61-fe91ae460f5f}" oem="" oemversion="" alephversion="494.5.0" machineid="0652eb69ffdc3ad3a41ae0a99e073200" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
May 07 15:43:20 dev11 update_engine[487]: <event eventtype="3" eventresult="0" errorcode="9"></event>
May 07 15:43:20 dev11 update_engine[487]: </app>
May 07 15:43:20 dev11 update_engine[487]: </request>
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:libcurl_http_fetcher.cc(62)] Starting/Resuming transfer
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:libcurl_http_fetcher.cc(51)] We are connected via Ethernet, Updates allowed: Yes
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:libcurl_http_fetcher.cc(185)] Setting up curl options for HTTPS
May 07 15:43:20 dev11 update_engine[487]: [0507/154320:INFO:libcurl_http_fetcher.cc(448)] Setting up timeout source: 1 seconds.
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:libcurl_http_fetcher.cc(261)] HTTP response code: 200
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:libcurl_http_fetcher.cc(318)] Transfer completed (200), 798 bytes downloaded
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:omaha_request_action.cc(588)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>
May 07 15:43:21 dev11 update_engine[487]: <response protocol="3.0" server="update.core-os.net">
May 07 15:43:21 dev11 update_engine[487]: <daystart elapsed_seconds="0"></daystart>
May 07 15:43:21 dev11 update_engine[487]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">
May 07 15:43:21 dev11 update_engine[487]: <updatecheck status="ok">
May 07 15:43:21 dev11 update_engine[487]: <urls>
May 07 15:43:21 dev11 update_engine[487]: <url codebase="https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/633.1.0/"></url>
May 07 15:43:21 dev11 update_engine[487]: </urls>
May 07 15:43:21 dev11 update_engine[487]: <manifest version="633.1.0">
May 07 15:43:21 dev11 update_engine[487]: <packages>
May 07 15:43:21 dev11 update_engine[487]: <package hash="RIWaOLeslMhokRhQzBkwmTZYCMk=" name="update.gz" size="129140021" required="false"></package>
May 07 15:43:21 dev11 update_engine[487]: </packages>
May 07 15:43:21 dev11 update_engine[487]: <actions>
May 07 15:43:21 dev11 update_engine[487]: <action event="postinstall" ChromeOSVersion="" sha256="B2RprH+KhBfSKARR7Ezd5cTqgFDbZCeQaBACg62yK1I=" needsadmin="false" IsDelta="false" DisablePayloadBackoff="true"></action>
May 07 15:43:21 dev11 update_engine[487]: </actions>
May 07 15:43:21 dev11 update_engine[487]: </manifest>
May 07 15:43:21 dev11 update_engine[487]: </updatecheck>
May 07 15:43:21 dev11 update_engine[487]: </app>
May 07 15:43:21 dev11 update_engine[487]: </response>
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:ERROR:omaha_request_action.cc(595)] HTTP reported success but Omaha reports an error.
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:action_processor.cc(65)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:update_attempter.cc(460)] Processing Done.
May 07 15:43:21 dev11 update_engine[487]: [0507/154321:INFO:update_attempter.cc(467)] Error event sent.

Michael Marineau

unread,
May 7, 2015, 3:59:03 AM5/7/15
to 沈雷, coreos-user
Ahh, yes. Access in China is an issue we will try to sort out soon. If
you have a proxy available you can configure update_engine to use it:
https://coreos.com/docs/cluster-management/setup/update-strategies/#updating-behind-a-proxy

Tomasen Sen

unread,
Dec 13, 2015, 2:50:44 AM12/13/15
to CoreOS User, she...@36kr.com
still failing even after setup proxy as https://coreos.com/docs/cluster-management/setup/update-strategies/#updating-behind-a-proxy  instructed.

-- Logs begin at Sun 2015-12-13 23:23:01 CST. --

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: <os version="Chateau" platform="CoreOS" sp="681.2.0_x86_64"></os>

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="681.2.0" track="stable" bootid="{9184eee5-b4f6-4365-a5bf-fdf017fdaf7f}" oem="" oemversion="" alephversion="681.2.0" machineid="9e0dd398054a4a10a7c1c5ca55599f92" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: <ping active="1"></ping>

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: <updatecheck></updatecheck>

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: <event eventtype="3" eventresult="2" previousversion=""></event>

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: </app>

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: </request>

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: [1213/154521:INFO:libcurl_http_fetcher.cc(48)] Starting/Resuming transfer

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: [1213/154521:INFO:libcurl_http_fetcher.cc(164)] Setting up curl options for HTTPS

Dec 13 15:45:21 iZ2353d1c5vZ update_engine[677]: [1213/154521:INFO:libcurl_http_fetcher.cc(427)] Setting up timeout source: 1 seconds.

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:libcurl_http_fetcher.cc(240)] HTTP response code: 200

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:libcurl_http_fetcher.cc(297)] Transfer completed (200), 798 bytes downloaded

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(574)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <response protocol="3.0" server="update.core-os.net">

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <daystart elapsed_seconds="0"></daystart>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <updatecheck status="ok">

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <urls>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <url codebase="https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/835.9.0/"></url>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </urls>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <manifest version="835.9.0">

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <packages>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <package hash="+mTpwbXNUUjGxyuyJV5aHqd99VU=" name="update.gz" size="174745441" required="false"></package>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </packages>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <actions>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: <action event="postinstall" ChromeOSVersion="" sha256="KYiQpxp3CledoSPmhCMLMCn1sfp/77dy3CKyfWWj7Ak=" needsadmin="false" IsDelta="false" DisablePayloadBackoff="true"></action>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </actions>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </manifest>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </updatecheck>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </app>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: </response>

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(427)] Found 1 url(s)

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(463)] Processing first of 1 package(s)

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(470)] Omaha Response package name = update.gz

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(482)] Url0: https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/835.9.0/update.gz

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(494)] Payload size = 174745441 bytes

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_request_action.cc(518)] Found 1 action(s). Processing the postinstall action.

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:payload_state.cc(199)] Payload backoff logic is disabled. Can proceed with the download

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_response_handler_action.cc(43)] Using Url0 as the download url this time

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_response_handler_action.cc(134)] Waiving payload hash checks since Omaha response only has HTTPS URL(s)

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:ERROR:delta_performer.cc(941)] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed.

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:omaha_response_handler_action.cc(71)] Using this install plan:

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:install_plan.cc(52)] InstallPlan: , new_update, url: https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/835.9.0/update.gz, payload size: 174745441, payload hash: KYiQpxp3CledoSPmhCMLMCn1sfp/77dy3CKyfWWj7Ak=, install_path: /dev/xvda4, kernel_install_path: , hash_checks_mandatory: false

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:utils.cc(633)] Setting cgroup cpu shares to  2

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:ERROR:utils.cc(118)] 0 == writer.Open(path, O_WRONLY | O_CREAT | O_TRUNC, 0600) failed: No such file or directory

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:ERROR:utils.cc(638)] Failed to change cgroup cpu shares to 2 using /sys/fs/cgroup/cpu/update-engine/cpu.shares

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction

Dec 13 15:45:23 iZ2353d1c5vZ update_engine[677]: [1213/154523:INFO:filesystem_copier_action.cc(323)] Filesystem size: 1065345024 bytes (260094x4096).

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: [1213/154541:INFO:filesystem_copier_action.cc(283)] Hash: cpCTup4kRyL3+J+JD78hBbm7Sya1lH3BFvT8n3tO6to=

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: [1213/154541:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting OmahaRequestAction

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: [1213/154541:INFO:omaha_request_action.cc(257)] Posting an Omaha request to https://public.update.core-os.net/v1/update/

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: [1213/154541:INFO:omaha_request_action.cc(258)] Request: <?xml version="1.0" encoding="UTF-8"?>

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: <os version="Chateau" platform="CoreOS" sp="681.2.0_x86_64"></os>

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="681.2.0" track="stable" bootid="{9184eee5-b4f6-4365-a5bf-fdf017fdaf7f}" oem="" oemversion="" alephversion="681.2.0" machineid="9e0dd398054a4a10a7c1c5ca55599f92" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: <event eventtype="13" eventresult="1"></event>

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: </app>

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: </request>

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: [1213/154541:INFO:libcurl_http_fetcher.cc(48)] Starting/Resuming transfer

Dec 13 15:45:41 iZ2353d1c5vZ update_engine[677]: [1213/154541:INFO:libcurl_http_fetcher.cc(164)] Setting up curl options for HTTPS

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:ERROR:libcurl_http_fetcher.cc(243)] Unable to get http response code: Couldn't resolve host 'public.update.core-os.net'

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:libcurl_http_fetcher.cc(274)] Transfer resulted in an error (0), 0 bytes downloaded

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:omaha_request_action.cc(574)] Omaha request response:

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting DownloadAction

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:install_plan.cc(52)] InstallPlan: , new_update, url: https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/835.9.0/update.gz, payload size: 174745441, payload hash: KYiQpxp3CledoSPmhCMLMCn1sfp/77dy3CKyfWWj7Ak=, install_path: /dev/xvda4, kernel_install_path: , hash_checks_mandatory: false

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:update_attempter.cc(408)] Download status: active

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:multi_range_http_fetcher.cc(29)] starting first transfer

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:multi_range_http_fetcher.cc(57)] starting transfer of range 0+?

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:libcurl_http_fetcher.cc(48)] Starting/Resuming transfer

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:libcurl_http_fetcher.cc(164)] Setting up curl options for HTTPS

Dec 13 15:45:43 iZ2353d1c5vZ update_engine[677]: [1213/154543:INFO:libcurl_http_fetcher.cc(427)] Setting up timeout source: 1 seconds.

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:ERROR:libcurl_http_fetcher.cc(243)] Unable to get http response code: Operation timed out after 0 milliseconds with 0 out of 0 bytes received

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:libcurl_http_fetcher.cc(274)] Transfer resulted in an error (0), 0 bytes downloaded

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:multi_range_http_fetcher.cc(150)] Received transfer complete.

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:multi_range_http_fetcher.cc(107)] TransferEnded w/ code 0

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:multi_range_http_fetcher.cc(141)] Done w/ all transfers

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:update_attempter.cc(408)] Download status: inactive

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: DownloadAction action failed. Aborting processing.

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type DownloadAction

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:update_attempter.cc(283)] Processing Done.

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:ERROR:update_attempter.cc(598)] Update failed.

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:payload_state.cc(97)] Updating payload state for error code: 9 (kActionCodeDownloadTransferError)

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:payload_state.cc(271)] Incrementing the URL failure count

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:payload_state.cc(421)] Current URL (Url0)'s Failure Count = 6

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:omaha_request_action.cc(257)] Posting an Omaha request to https://public.update.core-os.net/v1/update/

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:omaha_request_action.cc(258)] Request: <?xml version="1.0" encoding="UTF-8"?>

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: <os version="Chateau" platform="CoreOS" sp="681.2.0_x86_64"></os>

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="681.2.0" track="stable" bootid="{9184eee5-b4f6-4365-a5bf-fdf017fdaf7f}" oem="" oemversion="" alephversion="681.2.0" machineid="9e0dd398054a4a10a7c1c5ca55599f92" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: <event eventtype="3" eventresult="0" errorcode="9"></event>

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: </app>

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: </request>

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:libcurl_http_fetcher.cc(48)] Starting/Resuming transfer

Dec 13 15:46:13 iZ2353d1c5vZ update_engine[677]: [1213/154613:INFO:libcurl_http_fetcher.cc(164)] Setting up curl options for HTTPS

Dec 13 15:46:14 iZ2353d1c5vZ update_engine[677]: [1213/154614:INFO:libcurl_http_fetcher.cc(427)] Setting up timeout source: 1 seconds.

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:libcurl_http_fetcher.cc(240)] HTTP response code: 200

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:libcurl_http_fetcher.cc(297)] Transfer completed (200), 798 bytes downloaded

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:omaha_request_action.cc(574)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <response protocol="3.0" server="update.core-os.net">

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <daystart elapsed_seconds="0"></daystart>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok">

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <updatecheck status="ok">

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <urls>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <url codebase="https://commondatastorage.googleapis.com/update-storage.core-os.net/amd64-usr/835.9.0/"></url>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </urls>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <manifest version="835.9.0">

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <packages>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <package hash="+mTpwbXNUUjGxyuyJV5aHqd99VU=" name="update.gz" size="174745441" required="false"></package>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </packages>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <actions>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: <action event="postinstall" ChromeOSVersion="" sha256="KYiQpxp3CledoSPmhCMLMCn1sfp/77dy3CKyfWWj7Ak=" needsadmin="false" IsDelta="false" DisablePayloadBackoff="true"></action>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </actions>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </manifest>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </updatecheck>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </app>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: </response>

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:ERROR:omaha_request_action.cc(581)] HTTP reported success but Omaha reports an error.

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:action_processor.cc(65)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:update_attempter.cc(283)] Processing Done.

Dec 13 15:46:18 iZ2353d1c5vZ update_engine[677]: [1213/154618:INFO:update_attempter.cc(290)] Error event sent.


>> > [...

anton....@coreos.com

unread,
Jan 5, 2016, 10:33:10 AM1/5/16
to CoreOS User, she...@36kr.com
Dear Tomasen,

This is a bit hacky solution but I suppose you can try it: https://gist.github.com/kayrus/b47c466d8bbeb46822b1
This doc explains how you can create your own update server and download update.gz manually.

Another fancy solution which could be suitable for China users: https://github.com/coreroller/coreroller
But I didn't test it. Use it on your own risk.

Regards,
Anton
Reply all
Reply to author
Forward
0 new messages