2022-08-16T07:22:00.692197Z INFO update_engine: [main.cc(55)] A/B Update Engine starting 2022-08-16T07:22:00.698738Z INFO update_engine: [subprocess.cc(98)] Running "/usr/share/fydeos_shell/license-utils.sh id" 2022-08-16T07:22:01.069684Z INFO update_engine: [fydeos_license_checker.cc(41)] FydeOS license id: 9641f992ecd99e4ca9ffdb118bfe8f9a03914036 2022-08-16T07:22:01.072042Z INFO update_engine: [boot_control_chromeos.cc(180)] Booted from slot 0 (slot A) of 2 slots present on disk /dev/loop0 2022-08-16T07:22:01.072134Z INFO update_engine: [real_system_state.cc(60)] Booted in dev mode. 2022-08-16T07:22:01.072186Z INFO update_engine: [real_system_state.cc(61)] Booted non-official build. 2022-08-16T07:22:01.073242Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T07:22:01.073443Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = stable-channel 2022-08-16T07:22:01.073461Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T07:22:01.073518Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T07:22:01.073518Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T07:22:01.075090Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T07:22:01.082647Z INFO update_engine: [metrics_utils.cc(331)] Payload Attempt Number = 0 2022-08-16T07:22:01.083042Z INFO update_engine: [payload_state.cc(916)] Full Payload Attempt Number = 0 2022-08-16T07:22:01.083334Z INFO update_engine: [payload_state.cc(943)] Current URL Index = 0 2022-08-16T07:22:01.083488Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T07:22:01.083601Z INFO update_engine: [payload_state.cc(1000)] Current URL (Url0)'s Failure Count = 0 2022-08-16T07:22:01.083848Z INFO update_engine: [payload_state.cc(990)] URL Switch Count = 0 2022-08-16T07:22:01.084386Z INFO update_engine: [metrics_utils.cc(348)] Update Monotonic Timestamp Start = 8/15/2022 16:42:26 GMT 2022-08-16T07:22:01.085216Z INFO update_engine: [payload_state.cc(1157)] Update Duration Uptime = 0s 2022-08-16T07:22:01.086916Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpsServer = 0 2022-08-16T07:22:01.090085Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpsServer = 0 2022-08-16T07:22:01.091244Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpServer = 0 2022-08-16T07:22:01.091518Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpServer = 0 2022-08-16T07:22:01.093620Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpPeer = 0 2022-08-16T07:22:01.094382Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpPeer = 0 2022-08-16T07:22:01.097218Z INFO update_engine: [metrics_utils.cc(323)] Number of Reboots during current update attempt = 0 2022-08-16T07:22:01.097357Z INFO update_engine: [payload_state.cc(1230)] Num Responses Seen = 0 2022-08-16T07:22:01.097527Z INFO update_engine: [payload_state.cc(1125)] Setting rollback-happened to 0. 2022-08-16T07:22:01.097659Z INFO update_engine: [payload_state.cc(1381)] p2p First Attempt Timestamp = 1/1/1601 0:00:00 GMT 2022-08-16T07:22:01.100067Z INFO update_engine: [payload_state.cc(1367)] p2p Num Attempts = 0 2022-08-16T07:22:01.100325Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T07:22:01.100325Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T07:22:01.100727Z INFO update_engine: [daemon_chromeos.cc(61)] Waiting for DBus object to be registered. 2022-08-16T07:22:01.113163Z INFO update_engine: [daemon_chromeos.cc(89)] Requesting telemetry info from cros_healthd. 2022-08-16T07:22:01.113396Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T07:22:01.113429Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T07:22:01.113447Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T07:22:01.113661Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 7:25:03 GMT 2022-08-16T07:22:01.113685Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T07:22:01.113702Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T07:22:01.113719Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T07:22:01.113735Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T07:22:01.113751Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T07:22:01.113769Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 3m1.962231s 2022-08-16T07:22:01.114649Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T07:22:01.114691Z INFO update_engine: [update_attempter.cc(1928)] Not starting p2p at startup since it's not enabled. 2022-08-16T07:22:01.114761Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T07:22:01.114780Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on p2p_enabled 2022-08-16T07:22:01.114797Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T07:22:01.114814Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on au_p2p_enabled 2022-08-16T07:22:01.114831Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 11h59m59.999851s 2022-08-16T07:22:01.271064Z INFO update_engine: [update_attempter.cc(2024)] Repeated updates has been enabled. 2022-08-16T07:22:21.810606Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T07:22:46.134165Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting UpdateBootFlagsAction 2022-08-16T07:22:46.134818Z INFO update_engine: [update_boot_flags_action.cc(50)] Marking booted slot as good. 2022-08-16T07:22:46.134860Z INFO update_engine: [subprocess.cc(98)] Running "/usr/sbin/chromeos-setgoodkernel" 2022-08-16T07:22:46.229722Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action UpdateBootFlagsAction with code ErrorCode::kSuccess 2022-08-16T07:25:03.177966Z INFO update_engine: [evaluation_context.cc(113)] OnTimeout() called due to poll interval 2022-08-16T07:25:03.185471Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T07:25:03.189227Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T07:25:03.189252Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T07:25:03.189422Z INFO update_engine: [update_check_allowed_policy.cc(108)] Allowing update check. 2022-08-16T07:25:03.189448Z INFO update_engine: [update_attempter.cc(1105)] Running periodic update. 2022-08-16T07:25:03.191930Z INFO update_engine: [update_attempter.cc(254)] Last reported daily metrics 14h12m39.595719s ago. 2022-08-16T07:25:03.205833Z INFO update_engine: [update_attempter.cc(361)] Device policies/settings present 2022-08-16T07:25:03.208175Z INFO update_engine: [update_attempter.cc(505)] Scattering disabled since scatter factor is set to 0 2022-08-16T07:25:03.208276Z INFO update_engine: [update_attempter.cc(380)] p2p is not enabled - disallowing p2p for both downloading and sharing. 2022-08-16T07:25:03.208295Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T07:25:03.208321Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T07:25:03.209796Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T07:25:03.211388Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T07:25:03.211388Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T07:25:03.214696Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T07:25:03.214740Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable market_segment: "No value set for market_segment" 2022-08-16T07:25:03.214759Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable quick_fix_build_token: "No value set for quick_fix_build_token" 2022-08-16T07:25:03.214777Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_lts_tag: "No value set for release_lts_tag" 2022-08-16T07:25:03.214794Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_to_target_version: "No value set for rollback_to_target_version" 2022-08-16T07:25:03.214812Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_allowed_milestones: "No value set for rollback_allowed_milestones" 2022-08-16T07:25:03.225321Z INFO update_engine: [update_attempter.cc(448)] target_version_prefix = , rollback_allowed = 0, scatter_factor_in_seconds = 0s 2022-08-16T07:25:03.225382Z INFO update_engine: [update_attempter.cc(455)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s 2022-08-16T07:25:03.225419Z INFO update_engine: [update_attempter.cc(463)] Use p2p For Downloading = 0, Use p2p For Sharing = 0 2022-08-16T07:25:03.225437Z INFO update_engine: [update_attempter.cc(470)] forced to obey proxies 2022-08-16T07:25:03.225453Z INFO update_engine: [update_attempter.cc(473)] proxy manual checks: 1 2022-08-16T07:25:03.225911Z INFO update_engine: [update_attempter.cc(1773)] Scheduling an action processor start. 2022-08-16T07:25:03.226019Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T07:25:03.242486Z INFO update_engine: [subprocess.cc(98)] Running "vpd_get_value first_active_omaha_ping_sent" 2022-08-16T07:25:03.266654Z INFO update_engine: [hardware_chromeos.cc(348)] Failed to parse active_ping value: 2022-08-16T07:25:03.266777Z INFO update_engine: [omaha_request_action.cc(177)] Not generating Omaha InstallData as we have no prefs file and OOBE is not complete or not enabled. 2022-08-16T07:25:03.267986Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T07:25:03.268027Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T07:25:03.272550Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T07:25:03.287103Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T07:25:03.287251Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T07:25:06.317726Z INFO update_engine: [libcurl_http_fetcher.cc(583)] HTTP response unsuccessful (403) or no payload (333), nothing to do 2022-08-16T07:25:06.319174Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 403 2022-08-16T07:25:06.319632Z INFO update_engine: [libcurl_http_fetcher.cc(521)] Transfer resulted in an error (403), 0 bytes downloaded 2022-08-16T07:25:06.319653Z INFO update_engine: [libcurl_http_fetcher.cc(535)] No further proxies, indicating transfer complete 2022-08-16T07:25:06.319691Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T07:25:06.319710Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T07:25:06.319710Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T07:25:06.323609Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaRequestAction with code Unknown error: 2403 2022-08-16T07:25:06.323634Z INFO update_engine: [action_processor.cc(123)] ActionProcessor: Aborting processing due to failure. 2022-08-16T07:25:06.323691Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T07:25:06.323711Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T07:25:06.323729Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T07:25:06.323729Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T07:25:06.323752Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T07:25:06.323768Z INFO update_engine: [payload_state.cc(263)] Updating payload state for error code: 37 (ErrorCode::kOmahaErrorInHTTPResponse) 2022-08-16T07:25:06.323786Z INFO update_engine: [payload_state.cc(270)] Ignoring failures until we get a valid Omaha response. 2022-08-16T07:25:06.323802Z INFO update_engine: [update_attempter.cc(1759)] Reporting the error event 2022-08-16T07:25:06.324089Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T07:25:06.324122Z INFO update_engine: [omaha_request_action.cc(177)] Not generating Omaha InstallData as we have no prefs file and OOBE is not complete or not enabled. 2022-08-16T07:25:06.326264Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T07:25:06.326287Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T07:25:06.327660Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T07:25:06.327931Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T07:25:06.328048Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T07:25:07.443770Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 200 2022-08-16T07:25:07.445597Z INFO update_engine: [libcurl_http_fetcher.cc(562)] Transfer completed (200), 268 bytes downloaded 2022-08-16T07:25:07.445641Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T07:25:07.445668Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action OmahaRequestAction with code ErrorCode::kSuccess 2022-08-16T07:25:07.445686Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T07:25:07.445704Z INFO update_engine: [update_attempter.cc(1158)] Error event sent. 2022-08-16T07:25:07.446818Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T07:25:07.446841Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T07:25:07.446861Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T07:25:07.447097Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 8:12:16 GMT 2022-08-16T07:25:07.447133Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T07:25:07.447150Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T07:25:07.447166Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T07:25:07.447181Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T07:25:07.447198Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T07:25:07.447215Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T07:30:07.547280Z INFO update_engine: [evaluation_context.cc(113)] OnTimeout() called due to poll interval 2022-08-16T07:30:07.567231Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T07:30:07.567312Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T07:30:07.567369Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T07:30:07.568511Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 8:12:16 GMT 2022-08-16T07:30:07.568591Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T07:30:07.568644Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T07:30:07.568695Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T07:30:07.568746Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T07:30:07.568796Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T07:30:07.568849Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T07:31:43.954251Z INFO update_engine: [main.cc(72)] A/B Update Engine terminating with exit code 0 2022-08-16T07:31:43.954523Z INFO update_engine: [base_message_loop.cc(77)] Leaking 4 canceled tasks. 2022-08-16T13:05:10.199206Z INFO update_engine: [main.cc(55)] A/B Update Engine starting 2022-08-16T13:05:10.204963Z INFO update_engine: [subprocess.cc(98)] Running "/usr/share/fydeos_shell/license-utils.sh id" 2022-08-16T13:05:10.528143Z INFO update_engine: [fydeos_license_checker.cc(41)] FydeOS license id: 9641f992ecd99e4ca9ffdb118bfe8f9a03914036 2022-08-16T13:05:10.530707Z INFO update_engine: [boot_control_chromeos.cc(180)] Booted from slot 0 (slot A) of 2 slots present on disk /dev/loop0 2022-08-16T13:05:10.530783Z INFO update_engine: [real_system_state.cc(60)] Booted in dev mode. 2022-08-16T13:05:10.530839Z INFO update_engine: [real_system_state.cc(61)] Booted non-official build. 2022-08-16T13:05:10.532505Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T13:05:10.532723Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = stable-channel 2022-08-16T13:05:10.532742Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T13:05:10.532812Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T13:05:10.532812Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T13:05:10.534280Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T13:05:10.542567Z INFO update_engine: [metrics_utils.cc(331)] Payload Attempt Number = 0 2022-08-16T13:05:10.543278Z INFO update_engine: [payload_state.cc(916)] Full Payload Attempt Number = 0 2022-08-16T13:05:10.544494Z INFO update_engine: [payload_state.cc(943)] Current URL Index = 0 2022-08-16T13:05:10.546526Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T13:05:10.548115Z INFO update_engine: [payload_state.cc(1000)] Current URL (Url0)'s Failure Count = 0 2022-08-16T13:05:10.548818Z INFO update_engine: [payload_state.cc(990)] URL Switch Count = 0 2022-08-16T13:05:10.549726Z INFO update_engine: [metrics_utils.cc(348)] Update Monotonic Timestamp Start = 8/15/2022 16:42:26 GMT 2022-08-16T13:05:10.550350Z INFO update_engine: [payload_state.cc(1157)] Update Duration Uptime = 0s 2022-08-16T13:05:10.551013Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpsServer = 0 2022-08-16T13:05:10.551296Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpsServer = 0 2022-08-16T13:05:10.551563Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpServer = 0 2022-08-16T13:05:10.553962Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpServer = 0 2022-08-16T13:05:10.555657Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpPeer = 0 2022-08-16T13:05:10.557493Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpPeer = 0 2022-08-16T13:05:10.558083Z INFO update_engine: [metrics_utils.cc(323)] Number of Reboots during current update attempt = 0 2022-08-16T13:05:10.558195Z INFO update_engine: [payload_state.cc(1230)] Num Responses Seen = 0 2022-08-16T13:05:10.558578Z INFO update_engine: [payload_state.cc(1125)] Setting rollback-happened to 0. 2022-08-16T13:05:10.560312Z INFO update_engine: [payload_state.cc(1381)] p2p First Attempt Timestamp = 1/1/1601 0:00:00 GMT 2022-08-16T13:05:10.560795Z INFO update_engine: [payload_state.cc(1367)] p2p Num Attempts = 0 2022-08-16T13:05:10.561172Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T13:05:10.561172Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T13:05:10.561582Z INFO update_engine: [daemon_chromeos.cc(61)] Waiting for DBus object to be registered. 2022-08-16T13:05:10.574217Z INFO update_engine: [daemon_chromeos.cc(89)] Requesting telemetry info from cros_healthd. 2022-08-16T13:05:10.574479Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T13:05:10.574514Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T13:05:10.574533Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T13:05:10.574748Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 13:07:35 GMT 2022-08-16T13:05:10.574771Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T13:05:10.574789Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T13:05:10.574805Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T13:05:10.574821Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T13:05:10.574838Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T13:05:10.574856Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 2m24.960807s 2022-08-16T13:05:10.575765Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T13:05:10.575811Z INFO update_engine: [update_attempter.cc(1928)] Not starting p2p at startup since it's not enabled. 2022-08-16T13:05:10.575881Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T13:05:10.575901Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on p2p_enabled 2022-08-16T13:05:10.575919Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T13:05:10.575936Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on au_p2p_enabled 2022-08-16T13:05:10.575953Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 11h59m59.999848s 2022-08-16T13:05:10.719116Z INFO update_engine: [update_attempter.cc(2024)] Repeated updates has been enabled. 2022-08-16T07:35:30.335736Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T07:35:57.075920Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting UpdateBootFlagsAction 2022-08-16T07:35:57.076605Z INFO update_engine: [update_boot_flags_action.cc(50)] Marking booted slot as good. 2022-08-16T07:35:57.076643Z INFO update_engine: [subprocess.cc(98)] Running "/usr/sbin/chromeos-setgoodkernel" 2022-08-16T07:35:57.156701Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action UpdateBootFlagsAction with code ErrorCode::kSuccess 2022-08-16T07:37:16.139773Z INFO update_engine: [main.cc(72)] A/B Update Engine terminating with exit code 0 2022-08-16T07:37:16.158524Z INFO update_engine: [base_message_loop.cc(77)] Leaking 4 canceled tasks. 2022-08-16T13:31:31.227590Z INFO update_engine: [main.cc(55)] A/B Update Engine starting 2022-08-16T13:31:31.233720Z INFO update_engine: [subprocess.cc(98)] Running "/usr/share/fydeos_shell/license-utils.sh id" 2022-08-16T13:31:31.570863Z INFO update_engine: [fydeos_license_checker.cc(41)] FydeOS license id: 9641f992ecd99e4ca9ffdb118bfe8f9a03914036 2022-08-16T13:31:31.573389Z INFO update_engine: [boot_control_chromeos.cc(180)] Booted from slot 0 (slot A) of 2 slots present on disk /dev/loop0 2022-08-16T13:31:31.573488Z INFO update_engine: [real_system_state.cc(60)] Booted in dev mode. 2022-08-16T13:31:31.573545Z INFO update_engine: [real_system_state.cc(61)] Booted non-official build. 2022-08-16T13:31:31.575535Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T13:31:31.575851Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = stable-channel 2022-08-16T13:31:31.575870Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T13:31:31.575947Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T13:31:31.575947Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T13:31:31.577656Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T13:31:31.592393Z INFO update_engine: [metrics_utils.cc(331)] Payload Attempt Number = 0 2022-08-16T13:31:31.592901Z INFO update_engine: [payload_state.cc(916)] Full Payload Attempt Number = 0 2022-08-16T13:31:31.593158Z INFO update_engine: [payload_state.cc(943)] Current URL Index = 0 2022-08-16T13:31:31.593267Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T13:31:31.594029Z INFO update_engine: [payload_state.cc(1000)] Current URL (Url0)'s Failure Count = 0 2022-08-16T13:31:31.597354Z INFO update_engine: [payload_state.cc(990)] URL Switch Count = 0 2022-08-16T13:31:31.601859Z INFO update_engine: [metrics_utils.cc(348)] Update Monotonic Timestamp Start = 8/15/2022 16:42:26 GMT 2022-08-16T13:31:31.602327Z INFO update_engine: [payload_state.cc(1157)] Update Duration Uptime = 0s 2022-08-16T13:31:31.602603Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpsServer = 0 2022-08-16T13:31:31.603420Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpsServer = 0 2022-08-16T13:31:31.603691Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpServer = 0 2022-08-16T13:31:31.607020Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpServer = 0 2022-08-16T13:31:31.607360Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpPeer = 0 2022-08-16T13:31:31.609733Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpPeer = 0 2022-08-16T13:31:31.610457Z INFO update_engine: [metrics_utils.cc(323)] Number of Reboots during current update attempt = 0 2022-08-16T13:31:31.610702Z INFO update_engine: [payload_state.cc(1230)] Num Responses Seen = 0 2022-08-16T13:31:31.611359Z INFO update_engine: [payload_state.cc(1125)] Setting rollback-happened to 0. 2022-08-16T13:31:31.611773Z INFO update_engine: [payload_state.cc(1381)] p2p First Attempt Timestamp = 1/1/1601 0:00:00 GMT 2022-08-16T13:31:31.612127Z INFO update_engine: [payload_state.cc(1367)] p2p Num Attempts = 0 2022-08-16T13:31:31.612391Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T13:31:31.612391Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T13:31:31.612810Z INFO update_engine: [daemon_chromeos.cc(61)] Waiting for DBus object to be registered. 2022-08-16T13:31:31.623238Z INFO update_engine: [daemon_chromeos.cc(89)] Requesting telemetry info from cros_healthd. 2022-08-16T13:31:31.626123Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T13:31:31.626185Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T13:31:31.626205Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T13:31:31.626435Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 13:35:45 GMT 2022-08-16T13:31:31.626460Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T13:31:31.626476Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T13:31:31.626493Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T13:31:31.626509Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T13:31:31.626525Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T13:31:31.626543Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 4m13.952968s 2022-08-16T13:31:31.628846Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T13:31:31.628899Z INFO update_engine: [update_attempter.cc(1928)] Not starting p2p at startup since it's not enabled. 2022-08-16T13:31:31.628969Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T13:31:31.628989Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on p2p_enabled 2022-08-16T13:31:31.631044Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T13:31:31.631076Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on au_p2p_enabled 2022-08-16T13:31:31.631094Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 11h59m59.997794s 2022-08-16T13:31:31.824540Z INFO update_engine: [update_attempter.cc(2024)] Repeated updates has been enabled. 2022-08-16T08:01:53.893475Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T08:02:16.552572Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting UpdateBootFlagsAction 2022-08-16T08:02:16.553522Z INFO update_engine: [update_boot_flags_action.cc(50)] Marking booted slot as good. 2022-08-16T08:02:16.553568Z INFO update_engine: [subprocess.cc(98)] Running "/usr/sbin/chromeos-setgoodkernel" 2022-08-16T08:02:16.610356Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action UpdateBootFlagsAction with code ErrorCode::kSuccess 2022-08-16T08:03:39.892348Z INFO update_engine: [main.cc(72)] A/B Update Engine terminating with exit code 0 2022-08-16T08:03:39.893567Z INFO update_engine: [base_message_loop.cc(77)] Leaking 4 canceled tasks. 2022-08-16T09:32:19.799601Z INFO update_engine: [main.cc(55)] A/B Update Engine starting 2022-08-16T09:32:19.807877Z INFO update_engine: [subprocess.cc(98)] Running "/usr/share/fydeos_shell/license-utils.sh id" 2022-08-16T09:32:20.149376Z INFO update_engine: [fydeos_license_checker.cc(41)] FydeOS license id: 9641f992ecd99e4ca9ffdb118bfe8f9a03914036 2022-08-16T09:32:20.152189Z INFO update_engine: [boot_control_chromeos.cc(180)] Booted from slot 0 (slot A) of 2 slots present on disk /dev/loop0 2022-08-16T09:32:20.152282Z INFO update_engine: [real_system_state.cc(60)] Booted in dev mode. 2022-08-16T09:32:20.152339Z INFO update_engine: [real_system_state.cc(61)] Booted non-official build. 2022-08-16T09:32:20.154643Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:32:20.154873Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = stable-channel 2022-08-16T09:32:20.154892Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:32:20.154962Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:32:20.154962Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:32:20.156852Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:32:20.165682Z INFO update_engine: [metrics_utils.cc(331)] Payload Attempt Number = 0 2022-08-16T09:32:20.165813Z INFO update_engine: [payload_state.cc(916)] Full Payload Attempt Number = 0 2022-08-16T09:32:20.166105Z INFO update_engine: [payload_state.cc(943)] Current URL Index = 0 2022-08-16T09:32:20.166265Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:32:20.166435Z INFO update_engine: [payload_state.cc(1000)] Current URL (Url0)'s Failure Count = 0 2022-08-16T09:32:20.166699Z INFO update_engine: [payload_state.cc(990)] URL Switch Count = 0 2022-08-16T09:32:20.167556Z INFO update_engine: [metrics_utils.cc(348)] Update Monotonic Timestamp Start = 8/15/2022 16:42:26 GMT 2022-08-16T09:32:20.167823Z INFO update_engine: [payload_state.cc(1157)] Update Duration Uptime = 0s 2022-08-16T09:32:20.169901Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpsServer = 0 2022-08-16T09:32:20.170271Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpsServer = 0 2022-08-16T09:32:20.170620Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpServer = 0 2022-08-16T09:32:20.170918Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpServer = 0 2022-08-16T09:32:20.174511Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpPeer = 0 2022-08-16T09:32:20.175294Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpPeer = 0 2022-08-16T09:32:20.177912Z INFO update_engine: [metrics_utils.cc(323)] Number of Reboots during current update attempt = 0 2022-08-16T09:32:20.178273Z INFO update_engine: [payload_state.cc(1230)] Num Responses Seen = 0 2022-08-16T09:32:20.178498Z INFO update_engine: [payload_state.cc(1125)] Setting rollback-happened to 0. 2022-08-16T09:32:20.178723Z INFO update_engine: [payload_state.cc(1381)] p2p First Attempt Timestamp = 1/1/1601 0:00:00 GMT 2022-08-16T09:32:20.178982Z INFO update_engine: [payload_state.cc(1367)] p2p Num Attempts = 0 2022-08-16T09:32:20.180744Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T09:32:20.180744Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T09:32:20.181195Z INFO update_engine: [daemon_chromeos.cc(61)] Waiting for DBus object to be registered. 2022-08-16T09:32:20.188140Z INFO update_engine: [daemon_chromeos.cc(89)] Requesting telemetry info from cros_healthd. 2022-08-16T09:32:20.188529Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:32:20.189612Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:32:20.189637Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:32:20.189843Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 9:41:15 GMT 2022-08-16T09:32:20.189868Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:32:20.189885Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:32:20.189901Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:32:20.189917Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:32:20.189933Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:32:20.189951Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T09:32:20.197607Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T09:32:20.197697Z INFO update_engine: [update_attempter.cc(1928)] Not starting p2p at startup since it's not enabled. 2022-08-16T09:32:20.197781Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T09:32:20.197802Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on p2p_enabled 2022-08-16T09:32:20.197819Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:32:20.197836Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on au_p2p_enabled 2022-08-16T09:32:20.197853Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 11h59m59.999826s 2022-08-16T09:32:20.283694Z INFO update_engine: [update_attempter.cc(2024)] Repeated updates has been enabled. 2022-08-16T09:32:40.139638Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T09:33:05.193092Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting UpdateBootFlagsAction 2022-08-16T09:33:05.195141Z INFO update_engine: [update_boot_flags_action.cc(50)] Marking booted slot as good. 2022-08-16T09:33:05.195192Z INFO update_engine: [subprocess.cc(98)] Running "/usr/sbin/chromeos-setgoodkernel" 2022-08-16T09:33:05.342802Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action UpdateBootFlagsAction with code ErrorCode::kSuccess 2022-08-16T09:34:05.081303Z INFO update_engine: [common_service.cc(82)] Attempt update: app_version="" omaha_url="" flags=0x0 interactive=yes 2022-08-16T09:34:05.082103Z INFO update_engine: [update_attempter.cc(979)] Forced update check requested. 2022-08-16T09:34:05.082324Z INFO update_engine: [update_attempter.cc(2002)] Non-official build; allowing any update source. 2022-08-16T09:34:05.082504Z INFO update_engine: [evaluation_context.cc(108)] ValueChanged() called for variable forced_update_requested 2022-08-16T09:34:05.082545Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:34:05.082566Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:34:05.082585Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:34:05.082606Z INFO update_engine: [interactive_update_policy_impl.cc(40)] Forced update signaled (interactive), allowing update check. 2022-08-16T09:34:05.082639Z INFO update_engine: [update_attempter.cc(1105)] Running interactive update. 2022-08-16T09:34:05.083211Z INFO update_engine: [update_attempter.cc(254)] Last reported daily metrics 16h21m41.487434s ago. 2022-08-16T09:34:05.085211Z INFO update_engine: [update_attempter.cc(361)] Device policies/settings present 2022-08-16T09:34:05.085739Z INFO update_engine: [update_attempter.cc(505)] Scattering disabled since scatter factor is set to 0 2022-08-16T09:34:05.085796Z INFO update_engine: [update_attempter.cc(380)] p2p is not enabled - disallowing p2p for both downloading and sharing. 2022-08-16T09:34:05.085816Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:34:05.085835Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:34:05.086973Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:34:05.087070Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:34:05.087070Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:34:05.088814Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:34:05.088879Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable market_segment: "No value set for market_segment" 2022-08-16T09:34:05.088899Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable quick_fix_build_token: "No value set for quick_fix_build_token" 2022-08-16T09:34:05.088917Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_lts_tag: "No value set for release_lts_tag" 2022-08-16T09:34:05.088935Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_to_target_version: "No value set for rollback_to_target_version" 2022-08-16T09:34:05.088953Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_allowed_milestones: "No value set for rollback_allowed_milestones" 2022-08-16T09:34:05.089946Z INFO update_engine: [update_attempter.cc(448)] target_version_prefix = , rollback_allowed = 0, scatter_factor_in_seconds = 0s 2022-08-16T09:34:05.089986Z INFO update_engine: [update_attempter.cc(455)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s 2022-08-16T09:34:05.090020Z INFO update_engine: [update_attempter.cc(463)] Use p2p For Downloading = 0, Use p2p For Sharing = 0 2022-08-16T09:34:05.090040Z INFO update_engine: [update_attempter.cc(470)] forced to obey proxies 2022-08-16T09:34:05.090056Z INFO update_engine: [update_attempter.cc(473)] proxy manual checks: 1 2022-08-16T09:34:05.090396Z INFO update_engine: [update_attempter.cc(1773)] Scheduling an action processor start. 2022-08-16T09:34:05.090506Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:34:05.091381Z INFO update_engine: [subprocess.cc(98)] Running "vpd_get_value first_active_omaha_ping_sent" 2022-08-16T09:34:05.108585Z INFO update_engine: [hardware_chromeos.cc(348)] Failed to parse active_ping value: 2022-08-16T09:34:05.108726Z INFO update_engine: [omaha_request_action.cc(177)] Not generating Omaha InstallData as we have no prefs file and OOBE is not complete or not enabled. 2022-08-16T09:34:05.110056Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:34:05.110089Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:34:05.111917Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:34:05.113801Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:34:05.113914Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:34:06.779767Z INFO update_engine: [libcurl_http_fetcher.cc(583)] HTTP response unsuccessful (403) or no payload (333), nothing to do 2022-08-16T09:34:06.781114Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 403 2022-08-16T09:34:06.781544Z INFO update_engine: [libcurl_http_fetcher.cc(521)] Transfer resulted in an error (403), 0 bytes downloaded 2022-08-16T09:34:06.781565Z INFO update_engine: [libcurl_http_fetcher.cc(535)] No further proxies, indicating transfer complete 2022-08-16T09:34:06.781583Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:34:06.781600Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:34:06.781600Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:34:06.801053Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaRequestAction with code Unknown error: 2403 2022-08-16T09:34:06.801093Z INFO update_engine: [action_processor.cc(123)] ActionProcessor: Aborting processing due to failure. 2022-08-16T09:34:06.806409Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:34:06.806483Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:34:06.806503Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:34:06.806503Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:34:06.806530Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:34:06.806547Z INFO update_engine: [payload_state.cc(263)] Updating payload state for error code: 37 (ErrorCode::kOmahaErrorInHTTPResponse) 2022-08-16T09:34:06.806567Z INFO update_engine: [payload_state.cc(270)] Ignoring failures until we get a valid Omaha response. 2022-08-16T09:34:06.806583Z INFO update_engine: [update_attempter.cc(1759)] Reporting the error event 2022-08-16T09:34:06.806809Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:34:06.806844Z INFO update_engine: [omaha_request_action.cc(177)] Not generating Omaha InstallData as we have no prefs file and OOBE is not complete or not enabled. 2022-08-16T09:34:06.809823Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:34:06.809881Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:34:06.812142Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:34:06.812486Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:34:06.812595Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:34:08.631548Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 200 2022-08-16T09:34:08.633454Z INFO update_engine: [libcurl_http_fetcher.cc(562)] Transfer completed (200), 268 bytes downloaded 2022-08-16T09:34:08.633494Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:34:08.633522Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action OmahaRequestAction with code ErrorCode::kSuccess 2022-08-16T09:34:08.633540Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:34:08.633560Z INFO update_engine: [update_attempter.cc(1158)] Error event sent. 2022-08-16T09:34:08.635077Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:34:08.635108Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:34:08.635128Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:34:08.635184Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 10:20:55 GMT 2022-08-16T09:34:08.635216Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:34:08.635233Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:34:08.635249Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:34:08.635265Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:34:08.635282Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:34:08.635300Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T09:34:22.800846Z INFO update_engine: [common_service.cc(205)] Setting destination channel to: beta-channel 2022-08-16T09:34:22.800916Z INFO update_engine: [omaha_request_params.cc(196)] SetTargetChannel called with beta-channel, Is Powerwash Allowed = false. Current channel = stable-channel, existing target channel = stable-channel, download channel = stable-channel 2022-08-16T09:34:22.806977Z INFO update_engine: [common_service.cc(82)] Attempt update: app_version="" omaha_url="" flags=0x0 interactive=yes 2022-08-16T09:34:22.807096Z INFO update_engine: [update_attempter.cc(979)] Forced update check requested. 2022-08-16T09:34:22.807260Z INFO update_engine: [update_attempter.cc(2002)] Non-official build; allowing any update source. 2022-08-16T09:34:22.807354Z INFO update_engine: [evaluation_context.cc(108)] ValueChanged() called for variable forced_update_requested 2022-08-16T09:34:22.807397Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:34:22.807416Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:34:22.807435Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:34:22.807454Z INFO update_engine: [interactive_update_policy_impl.cc(40)] Forced update signaled (interactive), allowing update check. 2022-08-16T09:34:22.807475Z INFO update_engine: [update_attempter.cc(1105)] Running interactive update. 2022-08-16T09:34:22.808103Z INFO update_engine: [update_attempter.cc(254)] Last reported daily metrics 16h21m59.21228s ago. 2022-08-16T09:34:22.810195Z INFO update_engine: [update_attempter.cc(361)] Device policies/settings present 2022-08-16T09:34:22.810271Z INFO update_engine: [update_attempter.cc(505)] Scattering disabled since scatter factor is set to 0 2022-08-16T09:34:22.810307Z INFO update_engine: [update_attempter.cc(380)] p2p is not enabled - disallowing p2p for both downloading and sharing. 2022-08-16T09:34:22.810326Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:34:22.810344Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:34:22.810677Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = beta-channel 2022-08-16T09:34:22.810696Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:34:22.810769Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:34:22.810769Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:34:22.810837Z INFO update_engine: [omaha_request_params.cc(108)] Disabling deltas as a channel change to beta-channel is pending, with is_powerwash_allowed=false 2022-08-16T09:34:22.811496Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:34:22.811534Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable market_segment: "No value set for market_segment" 2022-08-16T09:34:22.811553Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable quick_fix_build_token: "No value set for quick_fix_build_token" 2022-08-16T09:34:22.811571Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_lts_tag: "No value set for release_lts_tag" 2022-08-16T09:34:22.811588Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_to_target_version: "No value set for rollback_to_target_version" 2022-08-16T09:34:22.811606Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_allowed_milestones: "No value set for rollback_allowed_milestones" 2022-08-16T09:34:22.813084Z INFO update_engine: [update_attempter.cc(448)] target_version_prefix = , rollback_allowed = 0, scatter_factor_in_seconds = 0s 2022-08-16T09:34:22.813115Z INFO update_engine: [update_attempter.cc(455)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s 2022-08-16T09:34:22.813135Z INFO update_engine: [update_attempter.cc(463)] Use p2p For Downloading = 0, Use p2p For Sharing = 0 2022-08-16T09:34:22.813412Z INFO update_engine: [update_attempter.cc(1773)] Scheduling an action processor start. 2022-08-16T09:34:22.813604Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:34:22.813674Z INFO update_engine: [subprocess.cc(98)] Running "vpd_get_value first_active_omaha_ping_sent" 2022-08-16T09:34:22.830015Z INFO update_engine: [hardware_chromeos.cc(348)] Failed to parse active_ping value: 2022-08-16T09:34:22.830143Z INFO update_engine: [omaha_request_action.cc(177)] Not generating Omaha InstallData as we have no prefs file and OOBE is not complete or not enabled. 2022-08-16T09:34:22.832318Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:34:22.832356Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:34:22.834807Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:34:22.836889Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:34:22.837050Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:34:22.837682Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T09:34:25.936821Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 200 2022-08-16T09:34:25.938567Z INFO update_engine: [libcurl_http_fetcher.cc(562)] Transfer completed (200), 316 bytes downloaded 2022-08-16T09:34:25.938598Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:34:25.942654Z INFO update_engine: [subprocess.cc(98)] Running "vpd_get_value first_active_omaha_ping_sent" 2022-08-16T09:34:25.954995Z INFO update_engine: [hardware_chromeos.cc(348)] Failed to parse active_ping value: 2022-08-16T09:34:25.955110Z INFO update_engine: [subprocess.cc(98)] Running "vpd -i RW_VPD -s first_active_omaha_ping_sent=1" 2022-08-16T09:34:25.961915Z ERROR update_engine: [hardware_chromeos.cc(361)] Failed to set vpd key for first_active_omaha_ping_sent with exit code: 4 with output: and error: File [/usr/share/oem/.oem_licence] cannot be opened for write. saveFile('/usr/share/oem/.oem_licence') error: 4 2022-08-16T09:34:25.961915Z ERROR update_engine: [hardware_chromeos.cc(361)] Failed to set vpd key for first_active_omaha_ping_sent with exit code: 4 with output: and error: File [/usr/share/oem/.oem_licence] cannot be opened for write. saveFile('/usr/share/oem/.oem_licence') error: 4 2022-08-16T09:34:25.962132Z INFO update_engine: [omaha_request_action.cc(437)] For milestone N-0 firmware_key_version= kernel_key_version= 2022-08-16T09:34:25.962330Z INFO update_engine: [omaha_request_action.cc(532)] Set the Omaha InstallDate from Omaha Response to 19215 days. 2022-08-16T09:34:25.962352Z INFO update_engine: [omaha_request_action.cc(583)] No update for App {49BA18F3-93DB-4F43-B966-3BBC57881C42} 2022-08-16T09:34:25.963150Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaRequestAction with code ErrorCode::kSuccess 2022-08-16T09:34:25.963179Z INFO update_engine: [action_processor.cc(145)] ActionProcessor: starting OmahaResponseHandlerAction 2022-08-16T09:34:25.963198Z INFO update_engine: [omaha_response_handler_action.cc(63)] There are no updates. Aborting. 2022-08-16T09:34:25.963231Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaResponseHandlerAction with code ErrorCode::kNoUpdate 2022-08-16T09:34:25.963249Z INFO update_engine: [action_processor.cc(123)] ActionProcessor: Aborting processing due to failure. 2022-08-16T09:34:25.963358Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:34:25.963378Z INFO update_engine: [update_attempter.cc(1184)] No update. 2022-08-16T09:34:25.963860Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:34:25.963881Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:34:25.963904Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:34:25.963974Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 10:17:38 GMT 2022-08-16T09:34:25.966676Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:34:25.966695Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:34:25.966712Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:34:25.966729Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:34:25.966747Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:34:25.966768Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T09:34:50.521746Z INFO update_engine: [common_service.cc(205)] Setting destination channel to: stable-channel 2022-08-16T09:34:50.521820Z INFO update_engine: [omaha_request_params.cc(196)] SetTargetChannel called with stable-channel, Is Powerwash Allowed = false. Current channel = stable-channel, existing target channel = beta-channel, download channel = beta-channel 2022-08-16T09:34:50.526227Z INFO update_engine: [common_service.cc(82)] Attempt update: app_version="" omaha_url="" flags=0x0 interactive=yes 2022-08-16T09:34:50.526320Z INFO update_engine: [update_attempter.cc(979)] Forced update check requested. 2022-08-16T09:34:50.526528Z INFO update_engine: [update_attempter.cc(2002)] Non-official build; allowing any update source. 2022-08-16T09:34:50.526685Z INFO update_engine: [evaluation_context.cc(108)] ValueChanged() called for variable forced_update_requested 2022-08-16T09:34:50.526734Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:34:50.526758Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:34:50.526781Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:34:50.526805Z INFO update_engine: [interactive_update_policy_impl.cc(40)] Forced update signaled (interactive), allowing update check. 2022-08-16T09:34:50.526832Z INFO update_engine: [update_attempter.cc(1105)] Running interactive update. 2022-08-16T09:34:50.527524Z INFO update_engine: [update_attempter.cc(254)] Last reported daily metrics 16h22m26.93163s ago. 2022-08-16T09:34:50.528871Z INFO update_engine: [update_attempter.cc(361)] Device policies/settings present 2022-08-16T09:34:50.528930Z INFO update_engine: [update_attempter.cc(505)] Scattering disabled since scatter factor is set to 0 2022-08-16T09:34:50.528975Z INFO update_engine: [update_attempter.cc(380)] p2p is not enabled - disallowing p2p for both downloading and sharing. 2022-08-16T09:34:50.528997Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:34:50.529036Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:34:50.529424Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = stable-channel 2022-08-16T09:34:50.529448Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:34:50.529530Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:34:50.529530Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:34:50.532223Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:34:50.532299Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable market_segment: "No value set for market_segment" 2022-08-16T09:34:50.532339Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable quick_fix_build_token: "No value set for quick_fix_build_token" 2022-08-16T09:34:50.532363Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_lts_tag: "No value set for release_lts_tag" 2022-08-16T09:34:50.532385Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_to_target_version: "No value set for rollback_to_target_version" 2022-08-16T09:34:50.532408Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_allowed_milestones: "No value set for rollback_allowed_milestones" 2022-08-16T09:34:50.533493Z INFO update_engine: [update_attempter.cc(448)] target_version_prefix = , rollback_allowed = 0, scatter_factor_in_seconds = 0s 2022-08-16T09:34:50.533531Z INFO update_engine: [update_attempter.cc(455)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s 2022-08-16T09:34:50.533554Z INFO update_engine: [update_attempter.cc(463)] Use p2p For Downloading = 0, Use p2p For Sharing = 0 2022-08-16T09:34:50.533927Z INFO update_engine: [update_attempter.cc(1773)] Scheduling an action processor start. 2022-08-16T09:34:50.534163Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:34:50.535830Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:34:50.535865Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:34:50.540288Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:34:50.540797Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:34:50.540901Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:34:50.556323Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T09:34:52.766088Z INFO update_engine: [libcurl_http_fetcher.cc(583)] HTTP response unsuccessful (403) or no payload (303), nothing to do 2022-08-16T09:34:52.767656Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 403 2022-08-16T09:34:52.767848Z INFO update_engine: [libcurl_http_fetcher.cc(521)] Transfer resulted in an error (403), 0 bytes downloaded 2022-08-16T09:34:52.767868Z INFO update_engine: [libcurl_http_fetcher.cc(535)] No further proxies, indicating transfer complete 2022-08-16T09:34:52.767885Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:34:52.767902Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:34:52.767902Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:34:52.768505Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaRequestAction with code Unknown error: 2403 2022-08-16T09:34:52.768526Z INFO update_engine: [action_processor.cc(123)] ActionProcessor: Aborting processing due to failure. 2022-08-16T09:34:52.768557Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:34:52.768576Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:34:52.768601Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:34:52.768601Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:34:52.768624Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:34:52.768642Z INFO update_engine: [payload_state.cc(263)] Updating payload state for error code: 37 (ErrorCode::kOmahaErrorInHTTPResponse) 2022-08-16T09:34:52.768660Z INFO update_engine: [payload_state.cc(270)] Ignoring failures until we get a valid Omaha response. 2022-08-16T09:34:52.768676Z INFO update_engine: [update_attempter.cc(1759)] Reporting the error event 2022-08-16T09:34:52.768837Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:34:52.770138Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:34:52.770160Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:34:52.777823Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:34:52.783491Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:34:52.783643Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:34:53.891852Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 200 2022-08-16T09:34:53.893561Z INFO update_engine: [libcurl_http_fetcher.cc(562)] Transfer completed (200), 268 bytes downloaded 2022-08-16T09:34:53.893588Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:34:53.893614Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action OmahaRequestAction with code ErrorCode::kSuccess 2022-08-16T09:34:53.893650Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:34:53.893670Z INFO update_engine: [update_attempter.cc(1158)] Error event sent. 2022-08-16T09:34:53.894089Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:34:53.894112Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:34:53.894130Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:34:53.894180Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 10:15:25 GMT 2022-08-16T09:34:53.894203Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:34:53.894219Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:34:53.894549Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:34:53.894592Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:34:53.894608Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:34:53.894626Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T09:36:06.550285Z INFO update_engine: [common_service.cc(82)] Attempt update: app_version="" omaha_url="" flags=0x0 interactive=yes 2022-08-16T09:36:06.550419Z INFO update_engine: [update_attempter.cc(979)] Forced update check requested. 2022-08-16T09:36:06.550585Z INFO update_engine: [update_attempter.cc(2002)] Non-official build; allowing any update source. 2022-08-16T09:36:06.550698Z INFO update_engine: [evaluation_context.cc(108)] ValueChanged() called for variable forced_update_requested 2022-08-16T09:36:06.550740Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:36:06.550759Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:36:06.550777Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:36:06.550796Z INFO update_engine: [interactive_update_policy_impl.cc(40)] Forced update signaled (interactive), allowing update check. 2022-08-16T09:36:06.550817Z INFO update_engine: [update_attempter.cc(1105)] Running interactive update. 2022-08-16T09:36:06.551537Z INFO update_engine: [update_attempter.cc(254)] Last reported daily metrics 16h23m42.955611s ago. 2022-08-16T09:36:06.552846Z INFO update_engine: [update_attempter.cc(361)] Device policies/settings present 2022-08-16T09:36:06.552910Z INFO update_engine: [update_attempter.cc(505)] Scattering disabled since scatter factor is set to 0 2022-08-16T09:36:06.552947Z INFO update_engine: [update_attempter.cc(380)] p2p is not enabled - disallowing p2p for both downloading and sharing. 2022-08-16T09:36:06.552965Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:36:06.552984Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:36:06.553855Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:36:06.553941Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:36:06.553941Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:36:06.556412Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:36:06.556483Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable market_segment: "No value set for market_segment" 2022-08-16T09:36:06.556504Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable quick_fix_build_token: "No value set for quick_fix_build_token" 2022-08-16T09:36:06.556522Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_lts_tag: "No value set for release_lts_tag" 2022-08-16T09:36:06.556540Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_to_target_version: "No value set for rollback_to_target_version" 2022-08-16T09:36:06.556559Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_allowed_milestones: "No value set for rollback_allowed_milestones" 2022-08-16T09:36:06.557509Z INFO update_engine: [update_attempter.cc(448)] target_version_prefix = , rollback_allowed = 0, scatter_factor_in_seconds = 0s 2022-08-16T09:36:06.557543Z INFO update_engine: [update_attempter.cc(455)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s 2022-08-16T09:36:06.557562Z INFO update_engine: [update_attempter.cc(463)] Use p2p For Downloading = 0, Use p2p For Sharing = 0 2022-08-16T09:36:06.557892Z INFO update_engine: [update_attempter.cc(1773)] Scheduling an action processor start. 2022-08-16T09:36:06.558813Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:36:06.560232Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:36:06.560272Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:36:06.563897Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:36:06.564677Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:36:06.564786Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:36:08.688533Z INFO update_engine: [libcurl_http_fetcher.cc(583)] HTTP response unsuccessful (403) or no payload (303), nothing to do 2022-08-16T09:36:08.690351Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 403 2022-08-16T09:36:08.691884Z INFO update_engine: [libcurl_http_fetcher.cc(521)] Transfer resulted in an error (403), 0 bytes downloaded 2022-08-16T09:36:08.691961Z INFO update_engine: [libcurl_http_fetcher.cc(535)] No further proxies, indicating transfer complete 2022-08-16T09:36:08.691979Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:36:08.691997Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:36:08.691997Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:36:08.693226Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaRequestAction with code Unknown error: 2403 2022-08-16T09:36:08.693249Z INFO update_engine: [action_processor.cc(123)] ActionProcessor: Aborting processing due to failure. 2022-08-16T09:36:08.693285Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:36:08.693305Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:36:08.693341Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:36:08.693341Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:36:08.693364Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:36:08.693380Z INFO update_engine: [payload_state.cc(263)] Updating payload state for error code: 37 (ErrorCode::kOmahaErrorInHTTPResponse) 2022-08-16T09:36:08.693397Z INFO update_engine: [payload_state.cc(270)] Ignoring failures until we get a valid Omaha response. 2022-08-16T09:36:08.693413Z INFO update_engine: [update_attempter.cc(1759)] Reporting the error event 2022-08-16T09:36:08.695192Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:36:08.696453Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:36:08.696486Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:36:08.702961Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:36:08.703324Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:36:08.703420Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:36:09.944597Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 200 2022-08-16T09:36:09.946422Z INFO update_engine: [libcurl_http_fetcher.cc(562)] Transfer completed (200), 268 bytes downloaded 2022-08-16T09:36:09.946454Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:36:09.946480Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action OmahaRequestAction with code ErrorCode::kSuccess 2022-08-16T09:36:09.946499Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:36:09.946519Z INFO update_engine: [update_attempter.cc(1158)] Error event sent. 2022-08-16T09:36:09.946923Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:36:09.946944Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:36:09.946962Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:36:09.947020Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 10:25:26 GMT 2022-08-16T09:36:09.947045Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:36:09.947062Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:36:09.947078Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:36:09.947785Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:36:09.947803Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:36:09.947821Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T09:37:43.122647Z INFO update_engine: [main.cc(72)] A/B Update Engine terminating with exit code 0 2022-08-16T09:37:43.128215Z INFO update_engine: [base_message_loop.cc(77)] Leaking 9 canceled tasks. 2022-08-16T09:38:36.797662Z INFO update_engine: [main.cc(55)] A/B Update Engine starting 2022-08-16T09:38:36.816763Z INFO update_engine: [subprocess.cc(98)] Running "/usr/share/fydeos_shell/license-utils.sh id" 2022-08-16T09:38:37.191091Z INFO update_engine: [fydeos_license_checker.cc(41)] FydeOS license id: 9641f992ecd99e4ca9ffdb118bfe8f9a03914036 2022-08-16T09:38:37.195959Z INFO update_engine: [boot_control_chromeos.cc(180)] Booted from slot 0 (slot A) of 2 slots present on disk /dev/loop0 2022-08-16T09:38:37.196067Z INFO update_engine: [real_system_state.cc(60)] Booted in dev mode. 2022-08-16T09:38:37.196119Z INFO update_engine: [real_system_state.cc(61)] Booted non-official build. 2022-08-16T09:38:37.197017Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:38:37.201125Z INFO update_engine: [omaha_request_params.cc(223)] Download channel for this attempt = stable-channel 2022-08-16T09:38:37.201200Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:38:37.201296Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:38:37.201296Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:38:37.208149Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:38:37.215516Z INFO update_engine: [metrics_utils.cc(331)] Payload Attempt Number = 0 2022-08-16T09:38:37.215655Z INFO update_engine: [payload_state.cc(916)] Full Payload Attempt Number = 0 2022-08-16T09:38:37.215906Z INFO update_engine: [payload_state.cc(943)] Current URL Index = 0 2022-08-16T09:38:37.216069Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:38:37.216186Z INFO update_engine: [payload_state.cc(1000)] Current URL (Url0)'s Failure Count = 0 2022-08-16T09:38:37.217396Z INFO update_engine: [payload_state.cc(990)] URL Switch Count = 0 2022-08-16T09:38:37.217858Z INFO update_engine: [metrics_utils.cc(348)] Update Monotonic Timestamp Start = 8/15/2022 16:42:26 GMT 2022-08-16T09:38:37.218133Z INFO update_engine: [payload_state.cc(1157)] Update Duration Uptime = 0s 2022-08-16T09:38:37.218388Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpsServer = 0 2022-08-16T09:38:37.218638Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpsServer = 0 2022-08-16T09:38:37.218888Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpServer = 0 2022-08-16T09:38:37.219142Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpServer = 0 2022-08-16T09:38:37.219393Z INFO update_engine: [payload_state.cc(1198)] Current bytes downloaded for HttpPeer = 0 2022-08-16T09:38:37.223039Z INFO update_engine: [payload_state.cc(1220)] Total bytes downloaded for HttpPeer = 0 2022-08-16T09:38:37.223606Z INFO update_engine: [metrics_utils.cc(323)] Number of Reboots during current update attempt = 0 2022-08-16T09:38:37.223725Z INFO update_engine: [payload_state.cc(1230)] Num Responses Seen = 0 2022-08-16T09:38:37.223894Z INFO update_engine: [payload_state.cc(1125)] Setting rollback-happened to 0. 2022-08-16T09:38:37.226303Z INFO update_engine: [payload_state.cc(1381)] p2p First Attempt Timestamp = 1/1/1601 0:00:00 GMT 2022-08-16T09:38:37.226743Z INFO update_engine: [payload_state.cc(1367)] p2p Num Attempts = 0 2022-08-16T09:38:37.226966Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T09:38:37.226966Z ERROR update_engine: [real_system_state.cc(178)] Failed to set kernel_max_rollforward to infinity for device with test/dev image. 2022-08-16T09:38:37.227424Z INFO update_engine: [daemon_chromeos.cc(61)] Waiting for DBus object to be registered. 2022-08-16T09:38:37.235162Z INFO update_engine: [daemon_chromeos.cc(89)] Requesting telemetry info from cros_healthd. 2022-08-16T09:38:37.237679Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:38:37.237731Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:38:37.237749Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:38:37.237967Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 9:48:57 GMT 2022-08-16T09:38:37.237991Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:38:37.238018Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:38:37.238034Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:38:37.238050Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:38:37.238066Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:38:37.238083Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s 2022-08-16T09:38:37.247269Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T09:38:37.247371Z INFO update_engine: [update_attempter.cc(1928)] Not starting p2p at startup since it's not enabled. 2022-08-16T09:38:37.247471Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable au_p2p_enabled: "No value set for au_p2p_enabled" 2022-08-16T09:38:37.247491Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on p2p_enabled 2022-08-16T09:38:37.247509Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:38:37.247526Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on au_p2p_enabled 2022-08-16T09:38:37.247543Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 11h59m59.999805s 2022-08-16T09:38:37.332424Z INFO update_engine: [update_attempter.cc(2024)] Repeated updates has been enabled. 2022-08-16T09:38:57.309349Z INFO update_engine: [real_device_policy_provider.cc(78)] Reloading and re-scheduling device policy due to signal received. 2022-08-16T09:39:22.256125Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting UpdateBootFlagsAction 2022-08-16T09:39:22.256915Z INFO update_engine: [update_boot_flags_action.cc(50)] Marking booted slot as good. 2022-08-16T09:39:22.256958Z INFO update_engine: [subprocess.cc(98)] Running "/usr/sbin/chromeos-setgoodkernel" 2022-08-16T09:39:22.310829Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action UpdateBootFlagsAction with code ErrorCode::kSuccess 2022-08-16T09:39:48.023494Z INFO update_engine: [common_service.cc(82)] Attempt update: app_version="" omaha_url="" flags=0x0 interactive=yes 2022-08-16T09:39:48.024314Z INFO update_engine: [update_attempter.cc(979)] Forced update check requested. 2022-08-16T09:39:48.024520Z INFO update_engine: [update_attempter.cc(2002)] Non-official build; allowing any update source. 2022-08-16T09:39:48.024664Z INFO update_engine: [evaluation_context.cc(108)] ValueChanged() called for variable forced_update_requested 2022-08-16T09:39:48.024709Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:39:48.024730Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:39:48.024749Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:39:48.024771Z INFO update_engine: [interactive_update_policy_impl.cc(40)] Forced update signaled (interactive), allowing update check. 2022-08-16T09:39:48.024801Z INFO update_engine: [update_attempter.cc(1105)] Running interactive update. 2022-08-16T09:39:48.026526Z INFO update_engine: [update_attempter.cc(254)] Last reported daily metrics 16h27m24.429595s ago. 2022-08-16T09:39:48.027891Z INFO update_engine: [update_attempter.cc(361)] Device policies/settings present 2022-08-16T09:39:48.028429Z INFO update_engine: [update_attempter.cc(505)] Scattering disabled since scatter factor is set to 0 2022-08-16T09:39:48.028482Z INFO update_engine: [update_attempter.cc(380)] p2p is not enabled - disallowing p2p for both downloading and sharing. 2022-08-16T09:39:48.028501Z INFO update_engine: [payload_state.cc(594)] Current download source: Unknown 2022-08-16T09:39:48.028519Z INFO update_engine: [omaha_request_params.cc(72)] Initializing parameters for this update attempt 2022-08-16T09:39:48.029150Z INFO update_engine: [omaha_request_params.cc(83)] Running from channel stable-channel 2022-08-16T09:39:48.029215Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:39:48.029215Z ERROR update_engine: [hardware_chromeos.cc(199)] Unable to read crossystem key hwid 2022-08-16T09:39:48.029934Z INFO update_engine: [omaha_request_params.cc(166)] No target channel mandated by policy. 2022-08-16T09:39:48.029971Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable market_segment: "No value set for market_segment" 2022-08-16T09:39:48.029990Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable quick_fix_build_token: "No value set for quick_fix_build_token" 2022-08-16T09:39:48.030015Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_lts_tag: "No value set for release_lts_tag" 2022-08-16T09:39:48.030035Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_to_target_version: "No value set for rollback_to_target_version" 2022-08-16T09:39:48.030053Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable rollback_allowed_milestones: "No value set for rollback_allowed_milestones" 2022-08-16T09:39:48.031137Z INFO update_engine: [update_attempter.cc(448)] target_version_prefix = , rollback_allowed = 0, scatter_factor_in_seconds = 0s 2022-08-16T09:39:48.031183Z INFO update_engine: [update_attempter.cc(455)] Wall Clock Based Wait Enabled = 0, Update Check Count Wait Enabled = 0, Waiting Period = 0s 2022-08-16T09:39:48.031201Z INFO update_engine: [update_attempter.cc(463)] Use p2p For Downloading = 0, Use p2p For Sharing = 0 2022-08-16T09:39:48.031220Z INFO update_engine: [update_attempter.cc(470)] forced to obey proxies 2022-08-16T09:39:48.031236Z INFO update_engine: [update_attempter.cc(473)] proxy manual checks: 1 2022-08-16T09:39:48.031580Z INFO update_engine: [update_attempter.cc(1773)] Scheduling an action processor start. 2022-08-16T09:39:48.031688Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:39:48.036835Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:39:48.036891Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:39:48.039418Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:39:48.040593Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:39:48.040701Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:39:49.612356Z INFO update_engine: [libcurl_http_fetcher.cc(583)] HTTP response unsuccessful (403) or no payload (303), nothing to do 2022-08-16T09:39:49.613669Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 403 2022-08-16T09:39:49.614148Z INFO update_engine: [libcurl_http_fetcher.cc(521)] Transfer resulted in an error (403), 0 bytes downloaded 2022-08-16T09:39:49.614175Z INFO update_engine: [libcurl_http_fetcher.cc(535)] No further proxies, indicating transfer complete 2022-08-16T09:39:49.614193Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:39:49.614210Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:39:49.614210Z ERROR update_engine: [omaha_request_action.cc(710)] Omaha request network transfer failed with HTTPResponseCode=403 2022-08-16T09:39:49.616418Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished OmahaRequestAction with code Unknown error: 2403 2022-08-16T09:39:49.616456Z INFO update_engine: [action_processor.cc(123)] ActionProcessor: Aborting processing due to failure. 2022-08-16T09:39:49.617856Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:39:49.617896Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:39:49.617915Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:39:49.617915Z ERROR update_engine: [update_attempter.cc(1749)] Update failed. 2022-08-16T09:39:49.617939Z INFO update_engine: [utils.cc(789)] Converting error code 2403 to ErrorCode::kOmahaErrorInHTTPResponse 2022-08-16T09:39:49.617956Z INFO update_engine: [payload_state.cc(263)] Updating payload state for error code: 37 (ErrorCode::kOmahaErrorInHTTPResponse) 2022-08-16T09:39:49.617975Z INFO update_engine: [payload_state.cc(270)] Ignoring failures until we get a valid Omaha response. 2022-08-16T09:39:49.617991Z INFO update_engine: [update_attempter.cc(1759)] Reporting the error event 2022-08-16T09:39:49.618321Z INFO update_engine: [action_processor.cc(53)] ActionProcessor: starting OmahaRequestAction 2022-08-16T09:39:49.620364Z INFO update_engine: [omaha_request_action.cc(271)] Posting an Omaha request to https://up.fydeos.com/service/update2 2022-08-16T09:39:49.620406Z INFO update_engine: [omaha_request_action.cc(272)] Request: 2022-08-16T09:39:49.622460Z INFO update_engine: [libcurl_http_fetcher.cc(131)] Starting/Resuming transfer 2022-08-16T09:39:49.622803Z INFO update_engine: [libcurl_http_fetcher.cc(150)] Using proxy: no 2022-08-16T09:39:49.622891Z INFO update_engine: [libcurl_http_fetcher.cc(263)] Not setting http(s) curl options because we are running a dev/test image 2022-08-16T09:39:51.763468Z INFO update_engine: [libcurl_http_fetcher.cc(465)] HTTP response code: 200 2022-08-16T09:39:51.768601Z INFO update_engine: [libcurl_http_fetcher.cc(562)] Transfer completed (200), 268 bytes downloaded 2022-08-16T09:39:51.768673Z INFO update_engine: [omaha_request_action.cc(686)] Omaha request response: 2022-08-16T09:39:51.768718Z INFO update_engine: [action_processor.cc(118)] ActionProcessor: finished last action OmahaRequestAction with code ErrorCode::kSuccess 2022-08-16T09:39:51.768737Z INFO update_engine: [update_attempter.cc(1290)] Processing Done. 2022-08-16T09:39:51.770273Z INFO update_engine: [update_attempter.cc(1158)] Error event sent. 2022-08-16T09:39:51.770766Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable update_disabled: "No value set for update_disabled" 2022-08-16T09:39:51.770787Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable target_version_prefix: "No value set for target_version_prefix" 2022-08-16T09:39:51.770806Z WARNING update_engine: [evaluation_context-inl.h(43)] Error reading Variable release_channel_delegated: "No value set for release_channel_delegated" 2022-08-16T09:39:51.770857Z INFO update_engine: [next_update_check_policy_impl.cc(63)] Periodic check interval not satisfied, blocking until 8/16/2022 10:27:03 GMT 2022-08-16T09:39:51.773265Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on forced_update_requested 2022-08-16T09:39:51.773342Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on policy_is_loaded 2022-08-16T09:39:51.773360Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on release_channel_delegated 2022-08-16T09:39:51.773376Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on update_disabled 2022-08-16T09:39:51.773394Z INFO update_engine: [evaluation_context.cc(186)] Waiting for value on target_version_prefix 2022-08-16T09:39:51.773414Z INFO update_engine: [evaluation_context.cc(215)] Waiting for timeout in 5m0s