Created
April 11, 2014 01:08
-
-
Save mopemope/10435483 to your computer and use it in GitHub Desktop.
coreos update-engine log
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| -- Logs begin at Tue 2014-04-01 02:28:51 UTC, end at Fri 2014-04-11 00:59:21 UTC. -- | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: <updatecheck targetversionprefix=""></updatecheck> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: <event eventtype="3" eventresult="2" previousversion=""></event> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: </app> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: </request> | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 13:19:44 coreos1 update_engine[22503]: [0410/131944:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: </app> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: </response> | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:omaha_request_action.cc(522)] No update. | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting. | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing. | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:update_attempter.cc(563)] Processing Done. | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:update_attempter.cc(628)] No update. | |
| Apr 10 13:19:45 coreos1 update_engine[22503]: [0410/131945:INFO:update_check_scheduler.cc(84)] Next update check in 45m15s | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:prefs.cc(55)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:prefs.cc(55)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:ERROR:chrome_browser_proxy_resolver.cc(64)] Error getting dbus proxy for org.chromium.LibCrosService: GError(3): Could not get owner of name 'org.chromium.LibCrosService': no such name | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:ERROR:chrome_browser_proxy_resolver.cc(104)] proxy_ failed. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:WARNING:libpolicy.cc(29)] Could not load the device policy file. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:update_attempter.cc(229)] No device policies/settings present. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:update_attempter.cc(324)] Scattering disabled since scatter factor is set to 0 | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:omaha_request_params.cc(87)] Disabling deltas as a channel change is pending | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:update_attempter.cc(274)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:update_attempter.cc(281)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:update_attempter.cc(784)] Already updated boot flags. Skipping. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:update_attempter.cc(973)] Scheduling an action processor start. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: <updatecheck targetversionprefix=""></updatecheck> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: <event eventtype="3" eventresult="2" previousversion=""></event> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: </app> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: </request> | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 14:05:00 coreos1 update_engine[22503]: [0410/140500:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: </app> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: </response> | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:omaha_request_action.cc(522)] No update. | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting. | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing. | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:update_attempter.cc(563)] Processing Done. | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:update_attempter.cc(628)] No update. | |
| Apr 10 14:05:01 coreos1 update_engine[22503]: [0410/140501:INFO:update_check_scheduler.cc(84)] Next update check in 42m26s | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:prefs.cc(55)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:prefs.cc(55)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:ERROR:chrome_browser_proxy_resolver.cc(64)] Error getting dbus proxy for org.chromium.LibCrosService: GError(3): Could not get owner of name 'org.chromium.LibCrosService': no such name | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:ERROR:chrome_browser_proxy_resolver.cc(104)] proxy_ failed. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:WARNING:libpolicy.cc(29)] Could not load the device policy file. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:update_attempter.cc(229)] No device policies/settings present. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:update_attempter.cc(324)] Scattering disabled since scatter factor is set to 0 | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:omaha_request_params.cc(87)] Disabling deltas as a channel change is pending | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:update_attempter.cc(274)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:update_attempter.cc(281)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:update_attempter.cc(784)] Already updated boot flags. Skipping. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:update_attempter.cc(973)] Scheduling an action processor start. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: <updatecheck targetversionprefix=""></updatecheck> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: <event eventtype="3" eventresult="2" previousversion=""></event> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: </app> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: </request> | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 14:47:27 coreos1 update_engine[22503]: [0410/144727:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: </app> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: </response> | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:omaha_request_action.cc(522)] No update. | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting. | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing. | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:update_attempter.cc(563)] Processing Done. | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:update_attempter.cc(628)] No update. | |
| Apr 10 14:47:28 coreos1 update_engine[22503]: [0410/144728:INFO:update_check_scheduler.cc(84)] Next update check in 46m52s | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:prefs.cc(55)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:prefs.cc(55)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:ERROR:chrome_browser_proxy_resolver.cc(64)] Error getting dbus proxy for org.chromium.LibCrosService: GError(3): Could not get owner of name 'org.chromium.LibCrosService': no such name | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:ERROR:chrome_browser_proxy_resolver.cc(104)] proxy_ failed. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:WARNING:libpolicy.cc(29)] Could not load the device policy file. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:update_attempter.cc(229)] No device policies/settings present. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:update_attempter.cc(324)] Scattering disabled since scatter factor is set to 0 | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:omaha_request_params.cc(87)] Disabling deltas as a channel change is pending | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:update_attempter.cc(274)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:update_attempter.cc(281)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:update_attempter.cc(784)] Already updated boot flags. Skipping. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:update_attempter.cc(973)] Scheduling an action processor start. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: <updatecheck targetversionprefix=""></updatecheck> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: <event eventtype="3" eventresult="2" previousversion=""></event> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: </app> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: </request> | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 15:34:20 coreos1 update_engine[22503]: [0410/153420:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: </app> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: </response> | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:omaha_request_action.cc(522)] No update. | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting. | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing. | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:update_attempter.cc(563)] Processing Done. | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:update_attempter.cc(628)] No update. | |
| Apr 10 15:34:21 coreos1 update_engine[22503]: [0410/153421:INFO:update_check_scheduler.cc(84)] Next update check in 46m29s | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:prefs.cc(55)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:prefs.cc(55)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:ERROR:chrome_browser_proxy_resolver.cc(64)] Error getting dbus proxy for org.chromium.LibCrosService: GError(3): Could not get owner of name 'org.chromium.LibCrosService': no such name | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:ERROR:chrome_browser_proxy_resolver.cc(104)] proxy_ failed. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:WARNING:libpolicy.cc(29)] Could not load the device policy file. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:update_attempter.cc(229)] No device policies/settings present. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:update_attempter.cc(324)] Scattering disabled since scatter factor is set to 0 | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:omaha_request_params.cc(87)] Disabling deltas as a channel change is pending | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:update_attempter.cc(274)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:update_attempter.cc(281)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:update_attempter.cc(784)] Already updated boot flags. Skipping. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:update_attempter.cc(973)] Scheduling an action processor start. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: <updatecheck targetversionprefix=""></updatecheck> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: <event eventtype="3" eventresult="2" previousversion=""></event> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: </request> | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 16:20:50 coreos1 update_engine[22503]: [0410/162050:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 1183 bytes downloaded | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <updatecheck status="ok"> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <urls> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <url codebase="http://update-storage.core-os.net/amd64-usr/alpha/e3879cf6e2b25733d6e17b00b21b5769/"></url> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </urls> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <manifest version="282.0.0"> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <packages> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <package hash="1o+mDArQ8HiW1BCtKNE49fXaYCo=" name="update.gz" size="96717160" required="false"></package> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </packages> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <actions> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: <action event="postinstall" ChromeOSVersion="" sha256="C3Tk3VYg5KmKIDg6/URmLg0ttj/nAgh73ztigefVLv4=" needsadmin="false" IsDelta="false" DisablePayloadBackoff="true" MetadataSignatureRsa="m7npUm2UbZIjoupAIrFNx/zXbOpWktisuhHSITO53NlgKSKPpMXdL+6ZoTcnjo0od6Qn0Gcgb65rRP1K1clILLBY8kgRPppk78dkY8swnm4bwSiejG5/y/2daYf7mlVSJDPSqWPQWVqtTz5f54iYqkz+9XfwXAWAs+KgPkkL60juveWrssM/Djgp+t4o/GhPq/VvxumxaXSu3u9eTPtaRtZ5cbxCO5MutDXc1/fYET7jBc7WXn/Yc9mdEz4zlLzl6cMjGeyuiU86CmTx0Lz1KRJ8Ph9eMcEusquSXa5pDIV+ppJ+74pEn1n67BBA81whaou93cp9W1KmJofpQRiCmA==" MetadataSize="56015"></action> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </actions> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </manifest> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </updatecheck> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: </response> | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(555)] Found 1 url(s) | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(591)] Processing first of 1 package(s) | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(598)] Omaha Response package name = update.gz | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(610)] Url0: http://update-storage.core-os.net/amd64-usr/alpha/e3879cf6e2b25733d6e17b00b21b5769/update.gz | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(622)] Payload size = 96717160 bytes | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_request_action.cc(646)] Found 1 action(s). Processing the postinstall action. | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(51)] Resetting all persisted state as this is a new response | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(361)] Current Response Signature = | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: NumURLs = 1 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Url0 = http://update-storage.core-os.net/amd64-usr/alpha/e3879cf6e2b25733d6e17b00b21b5769/update.gz | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Payload Size = 96717160 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Payload Sha256 Hash = C3Tk3VYg5KmKIDg6/URmLg0ttj/nAgh73ztigefVLv4= | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Metadata Size = 56015 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Metadata Signature = m7npUm2UbZIjoupAIrFNx/zXbOpWktisuhHSITO53NlgKSKPpMXdL+6ZoTcnjo0od6Qn0Gcgb65rRP1K1clILLBY8kgRPppk78dkY8swnm4bwSiejG5/y/2daYf7mlVSJDPSqWPQWVqtTz5f54iYqkz+9XfwXAWAs+KgPkkL60juveWrssM/Djgp+t4o/GhPq/VvxumxaXSu3u9eTPtaRtZ5cbxCO5MutDXc1/fYET7jBc7WXn/Yc9mdEz4zlLzl6cMjGeyuiU86CmTx0Lz1KRJ8Ph9eMcEusquSXa5pDIV+ppJ+74pEn1n67BBA81whaou93cp9W1KmJofpQRiCmA== | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Is Delta Payload = 0 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Max Failure Count Per Url = 10 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: Disable Payload Backoff = 1 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(382)] Payload Attempt Number = 0 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(405)] Current URL Index = 0 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(426)] Current URL (Url0)'s Failure Count = 0 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(283)] Resetting backoff expiry time as payload backoff is disabled | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(453)] Backoff Expiry Time = 1/1/1601 0:00:00 GMT | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:payload_state.cc(200)] Payload backoff logic is disabled. Can proceed with the download | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_response_handler_action.cc(43)] Using Url0 as the download url this time | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_response_handler_action.cc(135)] Mandating payload hash checks since Omaha response contains non-HTTPS URL(s) | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:prefs.cc(55)] update-state-next-operation not present in /var/lib/update_engine/prefs | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:ERROR:delta_performer.cc(1108)] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:omaha_response_handler_action.cc(78)] Using this install plan: | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:install_plan.cc(61)] InstallPlan: , new_update, url: http://update-storage.core-os.net/amd64-usr/alpha/e3879cf6e2b25733d6e17b00b21b5769/update.gz, payload size: 96717160, payload hash: C3Tk3VYg5KmKIDg6/URmLg0ttj/nAgh73ztigefVLv4=, metadata size: 56015, metadata signature: m7npUm2UbZIjoupAIrFNx/zXbOpWktisuhHSITO53NlgKSKPpMXdL+6ZoTcnjo0od6Qn0Gcgb65rRP1K1clILLBY8kgRPppk78dkY8swnm4bwSiejG5/y/2daYf7mlVSJDPSqWPQWVqtTz5f54iYqkz+9XfwXAWAs+KgPkkL60juveWrssM/Djgp+t4o/GhPq/VvxumxaXSu3u9eTPtaRtZ5cbxCO5MutDXc1/fYET7jBc7WXn/Yc9mdEz4zlLzl6cMjGeyuiU86CmTx0Lz1KRJ8Ph9eMcEusquSXa5pDIV+ppJ+74pEn1n67BBA81whaou93cp9W1KmJofpQRiCmA==, install_path: /dev/vda4, kernel_install_path: , hash_checks_mandatory: true, powerwash_required: false | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:utils.cc(654)] Setting cgroup cpu shares to 2 | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:ERROR:utils.cc(118)] 0 == writer.Open(path, O_WRONLY | O_CREAT | O_TRUNC, 0600) failed: No such file or directory | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:ERROR:utils.cc(659)] Failed to change cgroup cpu shares to 2 using /sys/fs/cgroup/cpu/update-engine/cpu.shares | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction | |
| Apr 10 16:20:51 coreos1 update_engine[22503]: [0410/162051:INFO:filesystem_copier_action.cc(323)] Filesystem size: 1073741824 bytes (262144x4096). | |
| Apr 10 16:21:08 coreos1 update_engine[22503]: [0410/162108:INFO:filesystem_copier_action.cc(283)] Hash: XxlWoXN0kjR9q17UnpL2OVhaeME31IOB6wpElVzbFZc= | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162108:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting OmahaRequestAction | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162108:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162108:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: <event eventtype="13" eventresult="1"></event> | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: </request> | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162108:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162109:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162109:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162109:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162109:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162109:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 16:21:09 coreos1 update_engine[22503]: [0410/162109:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: </response> | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting DownloadAction | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:install_plan.cc(61)] InstallPlan: , new_update, url: http://update-storage.core-os.net/amd64-usr/alpha/e3879cf6e2b25733d6e17b00b21b5769/update.gz, payload size: 96717160, payload hash: C3Tk3VYg5KmKIDg6/URmLg0ttj/nAgh73ztigefVLv4=, metadata size: 56015, metadata signature: m7npUm2UbZIjoupAIrFNx/zXbOpWktisuhHSITO53NlgKSKPpMXdL+6ZoTcnjo0od6Qn0Gcgb65rRP1K1clILLBY8kgRPppk78dkY8swnm4bwSiejG5/y/2daYf7mlVSJDPSqWPQWVqtTz5f54iYqkz+9XfwXAWAs+KgPkkL60juveWrssM/Djgp+t4o/GhPq/VvxumxaXSu3u9eTPtaRtZ5cbxCO5MutDXc1/fYET7jBc7WXn/Yc9mdEz4zlLzl6cMjGeyuiU86CmTx0Lz1KRJ8Ph9eMcEusquSXa5pDIV+ppJ+74pEn1n67BBA81whaou93cp9W1KmJofpQRiCmA==, install_path: /dev/vda4, kernel_install_path: , hash_checks_mandatory: true, powerwash_required: false | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:update_attempter.cc(717)] Download status: active | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:multi_range_http_fetcher.cc(29)] starting first transfer | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:multi_range_http_fetcher.cc(57)] starting transfer of range 0+? | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 16:21:10 coreos1 update_engine[22503]: [0410/162110:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:delta_performer.cc(153)] Completed 0/? operations, 2125/96717160 bytes downloaded (0%), overall progress 0% | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:delta_performer.cc(370)] Manifest size in payload matches expected value from Omaha | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:omaha_hash_calculator.cc(163)] Decoded 256 bytes from 344 base64-encoded bytes | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:delta_performer.cc(856)] Manifest signature matches expected value in Omaha response | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:delta_performer.cc(277)] PartitionInfo new_rootfs_info sha256: jYNAcMGysyR4e4/fr5Kf4mEYVpHkwCvfQ7lCdll6nzE= size: 1073741824 | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:delta_performer.cc(1057)] Verifying source partitions. | |
| Apr 10 16:21:11 coreos1 update_engine[22503]: [0410/162111:INFO:delta_performer.cc(453)] Starting to apply update payload operations | |
| Apr 10 16:21:12 coreos1 update_engine[22503]: [0410/162112:INFO:delta_performer.cc(153)] Completed 134/1025 operations (13%), 7742609/96717160 bytes downloaded (8%), overall progress 10% | |
| Apr 10 16:21:14 coreos1 update_engine[22503]: [0410/162114:INFO:delta_performer.cc(153)] Completed 258/1025 operations (25%), 15488131/96717160 bytes downloaded (16%), overall progress 20% | |
| Apr 10 16:21:16 coreos1 update_engine[22503]: [0410/162116:INFO:delta_performer.cc(153)] Completed 369/1025 operations (36%), 24617867/96717160 bytes downloaded (25%), overall progress 30% | |
| Apr 10 16:21:19 coreos1 update_engine[22503]: [0410/162119:INFO:delta_performer.cc(153)] Completed 406/1025 operations (39%), 40622103/96717160 bytes downloaded (42%), overall progress 40% | |
| Apr 10 16:21:22 coreos1 update_engine[22503]: [0410/162122:INFO:delta_performer.cc(153)] Completed 492/1025 operations (48%), 52209249/96717160 bytes downloaded (53%), overall progress 50% | |
| Apr 10 16:21:25 coreos1 update_engine[22503]: [0410/162125:INFO:delta_performer.cc(153)] Completed 559/1025 operations (54%), 63839061/96717160 bytes downloaded (66%), overall progress 60% | |
| Apr 10 16:21:28 coreos1 update_engine[22503]: [0410/162128:INFO:delta_performer.cc(153)] Completed 648/1025 operations (63%), 75440347/96717160 bytes downloaded (78%), overall progress 70% | |
| Apr 10 16:21:31 coreos1 update_engine[22503]: [0410/162131:INFO:delta_performer.cc(153)] Completed 718/1025 operations (70%), 88625991/96717160 bytes downloaded (91%), overall progress 80% | |
| Apr 10 16:21:33 coreos1 update_engine[22503]: [0410/162133:INFO:delta_performer.cc(153)] Completed 861/1025 operations (84%), 94036107/96717160 bytes downloaded (97%), overall progress 90% | |
| Apr 10 16:21:34 coreos1 update_engine[22503]: [0410/162134:INFO:delta_performer.cc(883)] Skipping hash verification for signature operation 1025 | |
| Apr 10 16:21:34 coreos1 update_engine[22503]: [0410/162134:INFO:delta_performer.cc(796)] Extracted signature data of size 264 at 96660881 | |
| Apr 10 16:21:34 coreos1 update_engine[22503]: [0410/162134:INFO:delta_performer.cc(153)] Completed 1025/1025 operations (100%), 96717160/96717160 bytes downloaded (100%), overall progress 100% | |
| Apr 10 16:21:34 coreos1 update_engine[22503]: [0410/162134:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 16:21:35 coreos1 update_engine[22503]: [0410/162134:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 96717160 bytes downloaded | |
| Apr 10 16:21:35 coreos1 update_engine[22503]: [0410/162134:INFO:multi_range_http_fetcher.cc(150)] Received transfer complete. | |
| Apr 10 16:21:35 coreos1 update_engine[22503]: [0410/162134:INFO:multi_range_http_fetcher.cc(107)] TransferEnded w/ code 200 | |
| Apr 10 16:21:35 coreos1 update_engine[22503]: [0410/162134:INFO:multi_range_http_fetcher.cc(141)] Done w/ all transfers | |
| Apr 10 16:21:35 coreos1 update_engine[22503]: [0410/162135:INFO:update_attempter.cc(717)] Download status: inactive | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:delta_performer.cc(938)] Verifying delta payload using public key: /usr/share/update_engine/update-payload-key.pub.pem | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:payload_signer.cc(272)] signature size = 264 | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:payload_state.cc(70)] Payload downloaded successfully | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:payload_state.cc(247)] Incrementing the payload attempt number | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:payload_state.cc(382)] Payload Attempt Number = 1 | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:payload_state.cc(283)] Resetting backoff expiry time as payload backoff is disabled | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:payload_state.cc(453)] Backoff Expiry Time = 1/1/1601 0:00:00 GMT | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished DownloadAction, starting OmahaRequestAction | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: <event eventtype="14" eventresult="1"></event> | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: </request> | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162135:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162136:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162136:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162136:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162136:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162136:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 16:21:36 coreos1 update_engine[22503]: [0410/162136:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: [0410/162137:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: [0410/162137:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: [0410/162137:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: </response> | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: [0410/162137:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting FilesystemCopierAction | |
| Apr 10 16:21:37 coreos1 update_engine[22503]: [0410/162137:INFO:filesystem_copier_action.cc(315)] Filesystem size: 1073741824 | |
| Apr 10 16:21:46 coreos1 update_engine[22503]: [0410/162146:INFO:filesystem_copier_action.cc(283)] Hash: jYNAcMGysyR4e4/fr5Kf4mEYVpHkwCvfQ7lCdll6nzE= | |
| Apr 10 16:21:46 coreos1 update_engine[22503]: [0410/162146:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting PostinstallRunnerAction | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:subprocess.cc(46)] Subprocess output: | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: '/tmp/au_postint_mount.xNIv9S/boot/vmlinuz' -> '/tmp/postinst_esp.yUMxcOsDcj/syslinux/vmlinuz.B' | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: '/tmp/au_postint_mount.xNIv9S/boot/syslinux/root.B.cfg' -> '/tmp/postinst_esp.yUMxcOsDcj/syslinux/root.B.cfg' | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: '/tmp/au_postint_mount.xNIv9S/boot/grub/menu.lst.B' -> '/tmp/postinst_esp.yUMxcOsDcj/boot/grub/menu.lst' | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: start size part contents | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: 2492416 2097152 4 Label: "USR-B" | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: Type: Alias for coreos-rootfs | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: UUID: E03DD35C-7C2D-4A47-B3FE-27F15780A57C | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: Attr: priority=2 tries=1 successful=0 | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: COREOS_RELEASE_VERSION=282.0.0 | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: Setup USR-B (/dev/vda4) for next boot. | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:postinstall_runner_action.cc(79)] Postinst command succeeded | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished PostinstallRunnerAction, starting OmahaRequestAction | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: <os version="Chateau" platform="CoreOS" sp="273.0.0_x86_64"></os> | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="273.0.0" track="alpha" from_track="alpha" bootid="{e8770eb0-1892-48fe-85d5-27702b1a5da2}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: <event eventtype="3" eventresult="1"></event> | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: </request> | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 16:21:48 coreos1 update_engine[22503]: [0410/162148:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: </app> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: </response> | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:action_processor.cc(65)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:update_attempter.cc(563)] Processing Done. | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:update_attempter.cc(604)] Update successfully applied, waiting to reboot. | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:update_check_scheduler.cc(84)] Next update check in 40m50s | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: libmetrics: /var/log/metrics/uma-events: open: No such file or directory | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: [0410/162149:INFO:utils.cc(793)] Sending error code 0 (kActionCodeSuccess) to UMA metric: Installer.DevModeErrorCodes. Flags = TestOmahaUrl | |
| Apr 10 16:21:49 coreos1 update_engine[22503]: libmetrics: /var/log/metrics/uma-events: open: No such file or directory | |
| Apr 10 16:22:50 coreos1 systemd[1]: update-engine.service: main process exited, code=exited, status=1/FAILURE | |
| Apr 10 16:22:50 coreos1 systemd[1]: Stopped Update Engine. | |
| Apr 10 16:22:50 coreos1 systemd[1]: Unit update-engine.service entered failed state. | |
| -- Reboot -- | |
| Apr 10 16:23:05 coreos1 systemd[1]: Starting Update Engine... | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:main.cc(160)] Chrome OS Update Engine starting | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:omaha_request_params.cc(180)] Current Channel set to alpha from LSB file in rootfs | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:payload_state.cc(361)] Current Response Signature = | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: NumURLs = 1 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Url0 = http://update-storage.core-os.net/amd64-usr/alpha/e3879cf6e2b25733d6e17b00b21b5769/update.gz | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Payload Size = 96717160 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Payload Sha256 Hash = C3Tk3VYg5KmKIDg6/URmLg0ttj/nAgh73ztigefVLv4= | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Metadata Size = 56015 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Metadata Signature = m7npUm2UbZIjoupAIrFNx/zXbOpWktisuhHSITO53NlgKSKPpMXdL+6ZoTcnjo0od6Qn0Gcgb65rRP1K1clILLBY8kgRPppk78dkY8swnm4bwSiejG5/y/2daYf7mlVSJDPSqWPQWVqtTz5f54iYqkz+9XfwXAWAs+KgPkkL60juveWrssM/Djgp+t4o/GhPq/VvxumxaXSu3u9eTPtaRtZ5cbxCO5MutDXc1/fYET7jBc7WXn/Yc9mdEz4zlLzl6cMjGeyuiU86CmTx0Lz1KRJ8Ph9eMcEusquSXa5pDIV+ppJ+74pEn1n67BBA81whaou93cp9W1KmJofpQRiCmA== | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Is Delta Payload = 0 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Max Failure Count Per Url = 10 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: Disable Payload Backoff = 1 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:payload_state.cc(382)] Payload Attempt Number = 1 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:payload_state.cc(405)] Current URL Index = 0 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:payload_state.cc(426)] Current URL (Url0)'s Failure Count = 0 | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:payload_state.cc(453)] Backoff Expiry Time = 1/1/1601 0:00:00 GMT | |
| Apr 10 16:23:06 coreos1 systemd[1]: Started Update Engine. | |
| Apr 10 16:23:06 coreos1 update_engine[2887]: [0410/162306:INFO:update_check_scheduler.cc(84)] Next update check in 2m37s | |
| Apr 10 16:23:52 coreos1 update_engine[2887]: [0410/162352:INFO:update_attempter.cc(794)] Updating boot flags... | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:prefs.cc(55)] certificate-report-to-send-update not present in /var/lib/update_engine/prefs | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:prefs.cc(55)] certificate-report-to-send-download not present in /var/lib/update_engine/prefs | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:ERROR:chrome_browser_proxy_resolver.cc(64)] Error getting dbus proxy for org.chromium.LibCrosService: GError(3): Could not get owner of name 'org.chromium.LibCrosService': no such name | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:ERROR:chrome_browser_proxy_resolver.cc(104)] proxy_ failed. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:WARNING:libpolicy.cc(29)] Could not load the device policy file. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(229)] No device policies/settings present. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(324)] Scattering disabled since scatter factor is set to 0 | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:omaha_request_params.cc(87)] Disabling deltas as a channel change is pending | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(274)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(281)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(290)] forced to obey proxies | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(293)] proxy manual checks: 1 | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(784)] Already updated boot flags. Skipping. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:update_attempter.cc(973)] Scheduling an action processor start. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/ | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="scheduler" ismachine="1"> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: <os version="Chateau" platform="CoreOS" sp="282.0.0_x86_64"></os> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="282.0.0" track="alpha" from_track="alpha" bootid="{99f4bb73-a25f-4b50-aa77-49f58520ac99}" oem="" machineid="872761f03ba851fc93d357ec66bf1934" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" > | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: <updatecheck targetversionprefix=""></updatecheck> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: <event eventtype="3" eventresult="2" previousversion="273.0.0"></event> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: </app> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: </request> | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding. | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP | |
| Apr 10 16:25:44 coreos1 update_engine[2887]: [0410/162544:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds. | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 200 | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:libcurl_http_fetcher.cc(358)] Transfer completed (200), 267 bytes downloaded | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:omaha_request_action.cc(708)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: <response protocol="3.0" server="update.core-os.net"> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: <daystart elapsed_seconds="0"></daystart> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: <app appid="e96281a6-d1af-4bde-9a0a-97b76e56dc57" status="ok"> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: <updatecheck status="noupdate"></updatecheck> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: </app> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: </response> | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:omaha_request_action.cc(522)] No update. | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:omaha_response_handler_action.cc(36)] There are no updates. Aborting. | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaResponseHandlerAction action failed. Aborting processing. | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaResponseHandlerAction | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:update_attempter.cc(563)] Processing Done. | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:update_attempter.cc(628)] No update. | |
| Apr 10 16:25:45 coreos1 update_engine[2887]: [0410/162545:INFO:update_check_scheduler.cc(84)] Next update check in 48m57s |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment