journal.log

Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:dbus_service.cc(94)] Attempt update: app_version="" omaha_url="" interactive=yes
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(526)] New update check requested
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:gpio_handler.cc(588)] GPIOs not engaged, defaulting to normal mode
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849: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 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:ERROR:chrome_browser_proxy_resolver.cc(104)] proxy_ failed.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:WARNING:libpolicy.cc(29)] Could not load the device policy file.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(229)] No device policies/settings present.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(324)] Scattering disabled since scatter factor is set to 0
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:omaha_request_params.cc(87)] Disabling deltas as a channel change is pending
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(274)] update_disabled = false, target_version_prefix = , scatter_factor_in_seconds = 0s
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(281)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(290)] forced to obey proxies
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(293)] proxy manual checks: 5
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(784)] Already updated boot flags. Skipping.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:update_attempter.cc(973)] Scheduling an action processor start.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:prefs.cc(55)] last-active-ping-day not present in /var/lib/update_engine/prefs
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:prefs.cc(55)] last-roll-call-ping-day not present in /var/lib/update_engine/prefs
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: <os version="Chateau" platform="CoreOS" sp="278.0.0_x86_64"></os>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="278.0.0" track="alpha" from_track="alpha" bootid="{7b1b7798-b90b-404c-a443-89666fb66b1e}" oem=""ami"" machineid="aff8b604a661b55f29e0f0efb19aa15e" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: <ping active="1" a="-1" r="-1"></ping>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: <updatecheck targetversionprefix=""></updatecheck>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: <event eventtype="3" eventresult="2" previousversion=""></event>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: </app>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: </request>
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding.
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP
Apr 11 09:08:49 srv-khnb3 update_engine[2915]: [0411/090849:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(378)] HTTP response unsuccessful (400) or no payload (12), nothing to do
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 400
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(324)] Transfer resulted in an error (400), 0 bytes downloaded
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(335)] No further proxies, indicating transfer complete
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:omaha_request_action.cc(708)] Omaha request response:
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:ERROR:omaha_request_action.cc(723)] Omaha request network transfer failed.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:update_attempter.cc(563)] Processing Done.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:ERROR:update_attempter.cc(907)] Update failed.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:utils.cc(748)] Converting error code 2400 to kActionCodeOmahaErrorInHTTPResponse
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:payload_state.cc(97)] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse)
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:payload_state.cc(103)] Ignoring failures until we get a valid Omaha response.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:update_attempter.cc(911)] Reporting the error event
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:utils.cc(748)] Converting error code 2400 to kActionCodeOmahaErrorInHTTPResponse
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:utils.cc(793)] Sending error code 37 (kActionCodeOmahaErrorInHTTPResponse) to UMA metric: Installer.DevModeErrorCodes. Flags = TestOmahaUrl
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: libmetrics: /var/log/metrics/uma-events: open: No such file or directory
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:action_processor.cc(36)] ActionProcessor::StartProcessing: OmahaRequestAction
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:omaha_request_action.cc(354)] Posting an Omaha request to http://public.roller.core-os.net/v1/update/
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:omaha_request_action.cc(355)] Request: <?xml version="1.0" encoding="UTF-8"?>
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: <request protocol="3.0" version="CoreOSUpdateEngine-0.1.0.0" updaterversion="CoreOSUpdateEngine-0.1.0.0" installsource="ondemandupdate" ismachine="1">
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: <os version="Chateau" platform="CoreOS" sp="278.0.0_x86_64"></os>
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: <app appid="{e96281a6-d1af-4bde-9a0a-97b76e56dc57}" version="278.0.0" track="alpha" from_track="alpha" bootid="{7b1b7798-b90b-404c-a443-89666fb66b1e}" oem=""ami"" machineid="aff8b604a661b55f29e0f0efb19aa15e" lang="en-US" board="amd64-usr" hardware_class="" delta_okay="false" >
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: <event eventtype="3" eventresult="0" errorcode="268437856"></event>
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: </app>
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: </request>
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:WARNING:chrome_browser_proxy_resolver.cc(185)] dbus proxy object missing, continuing with no proxy.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:chrome_browser_proxy_resolver.cc(262)] Timeout handler called. Seems Chrome isn't responding.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(63)] Starting/Resuming transfer
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(78)] Using proxy: no
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(52)] We are connected via Ethernet, Updates allowed: Yes
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(194)] Setting up curl options for HTTP
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(488)] Setting up timeout source: 1 seconds.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(378)] HTTP response unsuccessful (400) or no payload (12), nothing to do
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(289)] HTTP response code: 400
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(324)] Transfer resulted in an error (400), 0 bytes downloaded
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:libcurl_http_fetcher.cc(335)] No further proxies, indicating transfer complete
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:omaha_request_action.cc(708)] Omaha request response:
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:action_processor.cc(65)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:update_attempter.cc(563)] Processing Done.
Apr 11 09:08:50 srv-khnb3 update_engine[2915]: [0411/090850:INFO:update_attempter.cc(570)] Error event sent.

stdout

[0411/090849:INFO:update_engine_client.cc(325)] Forcing an update by setting app_version to ForcedUpdate.
[0411/090849:INFO:update_engine_client.cc(327)] Initiating update check and install.
[0411/090849:INFO:update_engine_client.cc(333)] Waiting for update to complete.
LAST_CHECKED_TIME=0
PROGRESS=0.000000
CURRENT_OP=UPDATE_STATUS_IDLE
NEW_VERSION=0.0.0.0
NEW_SIZE=0
[0411/090854:ERROR:update_engine_client.cc(248)] Update failed.