Skip to content

Instantly share code, notes, and snippets.

@nooriro

nooriro/sunfish-update-log-201023-0117.txt

Created October 22, 2020 16:28
Show Gist options
  • Save nooriro/4854bddedfb1370a9a8e079dd24ac72d to your computer and use it in GitHub Desktop.
Save nooriro/4854bddedfb1370a9a8e079dd24ac72d to your computer and use it in GitHub Desktop.
Download ZIP
sunfish update log (FAILED LOG) / from QD4A.200805.003 to RP1A.201005.006 (using FULL OTA IMAGE) / Update URL (Full OTA Image): https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip
Raw
sunfish-update-log-201023-0117.txt
--------- beginning of system
--------- beginning of main
10-23 00:35:02.411 1030 1030 I update_engine: [1023/003502.411599:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 00:35:02.411 1030 1030 I update_engine: [1023/003502.411721:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 1260+105160
10-23 00:35:02.411 1030 1030 I update_engine: [1023/003502.411834:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
10-23 00:35:02.418 1030 1030 I update_engine: [1023/003502.418058:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
10-23 00:35:02.418 1030 1030 I update_engine: [1023/003502.418305:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
10-23 00:35:03.572 1030 1030 I update_engine: [1023/003503.572456:INFO:certificate_checker.cc(185)] Certificate changed from 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD to 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0.
10-23 00:35:04.494 1030 1030 I update_engine: [1023/003504.494277:INFO:certificate_checker.cc(185)] Certificate changed from 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0 to 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD.
10-23 00:35:05.469 1030 1030 I update_engine: [1023/003505.469262:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 00:35:05.698 1030 1030 I update_engine: [1023/003505.698458:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 00:35:05.701 1030 1030 I update_engine: [1023/003505.701326:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 00:35:05.702 1030 1030 I update_engine: [1023/003505.702279:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 00:35:05.703 1030 1030 I update_engine: [1023/003505.703252:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 00:35:05.703 1030 1030 I update_engine: [1023/003505.703332:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 00:35:05.709 1030 1030 I update_engine: [1023/003505.709439:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 00:35:05.733 1030 1030 I update_engine: [1023/003505.733528:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 00:35:05.741 1030 1030 I update_engine: [1023/003505.741202:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:05.955 1030 1030 I update_engine: [1023/003505.955294:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 00:35:06.013 1030 1030 I update_engine: [1023/003506.013016:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped vendor_b to device mapper (force_writable = 1); device path at /dev/block/dm-3
10-23 00:35:06.015 1030 1030 I update_engine: [1023/003506.015368:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.015 1030 1030 I update_engine: [1023/003506.015866:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 00:35:06.017 1030 1030 I update_engine: [1023/003506.017264:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.019 1030 1030 I update_engine: [1023/003506.018964:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.232 1030 1030 I update_engine: [1023/003506.231997:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 00:35:06.286 1030 1030 I update_engine: [1023/003506.286298:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-1
10-23 00:35:06.288 1030 1030 I update_engine: [1023/003506.288556:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.289 1030 1030 I update_engine: [1023/003506.289077:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 00:35:06.289 1030 1030 I update_engine: [1023/003506.289580:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.291 1030 1030 I update_engine: [1023/003506.291345:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.291 1030 1030 I update_engine: [1023/003506.291866:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 00:35:06.292 1030 1030 I update_engine: [1023/003506.292434:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.294 1030 1030 I update_engine: [1023/003506.294298:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.552 1030 1030 I update_engine: [1023/003506.552718:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 00:35:06.607 1030 1030 I update_engine: [1023/003506.607371:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-5
10-23 00:35:06.609 1030 1030 I update_engine: [1023/003506.609553:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.610 1030 1030 I update_engine: [1023/003506.610747:INFO:boot_control_android.cc(140)] system_ext_b is not in super partition metadata.
10-23 00:35:06.611 1030 1030 I update_engine: [1023/003506.611330:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.611 1030 1030 E update_engine: [1023/003506.611432:ERROR:boot_control_android.cc(215)] Device file /dev/block/bootdevice/by-name/system_ext_b does not exist.
10-23 00:35:06.612 1030 1030 I update_engine: [1023/003506.612814:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.613 1030 1030 I update_engine: [1023/003506.613117:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 00:35:06.613 1030 1030 I update_engine: [1023/003506.613590:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.615 1030 1030 I update_engine: [1023/003506.614972:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.615 1030 1030 I update_engine: [1023/003506.615397:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 00:35:06.615 1030 1030 I update_engine: [1023/003506.615847:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.617 1030 1030 I update_engine: [1023/003506.617231:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.617 1030 1030 I update_engine: [1023/003506.617586:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 00:35:06.618 1030 1030 I update_engine: [1023/003506.618043:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.619 1030 1030 I update_engine: [1023/003506.619446:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.619 1030 1030 I update_engine: [1023/003506.619778:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 00:35:06.620 1030 1030 I update_engine: [1023/003506.620174:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.621 1030 1030 I update_engine: [1023/003506.621399:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.621 1030 1030 I update_engine: [1023/003506.621679:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 00:35:06.622 1030 1030 I update_engine: [1023/003506.622029:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.623 1030 1030 I update_engine: [1023/003506.623033:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.623 1030 1030 I update_engine: [1023/003506.623226:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 00:35:06.623 1030 1030 I update_engine: [1023/003506.623562:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.624 1030 1030 I update_engine: [1023/003506.624564:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.624 1030 1030 I update_engine: [1023/003506.624800:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 00:35:06.625 1030 1030 I update_engine: [1023/003506.625252:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.626 1030 1030 I update_engine: [1023/003506.626534:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.626 1030 1030 I update_engine: [1023/003506.626830:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 00:35:06.627 1030 1030 I update_engine: [1023/003506.627219:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.628 1030 1030 I update_engine: [1023/003506.628399:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.628 1030 1030 I update_engine: [1023/003506.628620:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 00:35:06.628 1030 1030 I update_engine: [1023/003506.628962:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.630 1030 1030 I update_engine: [1023/003506.630023:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.630 1030 1030 I update_engine: [1023/003506.630225:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 00:35:06.630 1030 1030 I update_engine: [1023/003506.630572:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.631 1030 1030 I update_engine: [1023/003506.631945:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.632 1030 1030 I update_engine: [1023/003506.632243:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 00:35:06.632 1030 1030 I update_engine: [1023/003506.632678:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.633 1030 1030 I update_engine: [1023/003506.633787:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 00:35:06.634 1030 1030 I update_engine: [1023/003506.633996:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 00:35:06.634 1030 1030 I update_engine: [1023/003506.634420:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 00:35:06.634 1030 1030 E update_engine: [1023/003506.634498:ERROR:delta_performer.cc(933)] Unable to determine all the partition devices.
10-23 00:35:06.634 1030 1030 E update_engine: [1023/003506.634536:ERROR:download_action.cc(336)] Error ErrorCode::kInstallDeviceOpenError (7) in DeltaPerformer's Write method when processing the received payload -- Terminating processing
10-23 00:35:06.634 1030 1030 I update_engine: [1023/003506.634588:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
10-23 00:35:06.641 1030 1030 I update_engine: [1023/003506.641562:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 00:35:06.641 1030 1030 I update_engine: [1023/003506.641762:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
10-23 00:35:06.641 1030 1030 I update_engine: [1023/003506.641798:INFO:multi_range_http_fetcher.cc(131)] Terminating.
10-23 00:35:06.650 1030 1030 I update_engine: [1023/003506.650248:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kInstallDeviceOpenError
10-23 00:35:06.650 1030 1030 I update_engine: [1023/003506.650539:INFO:action_processor.cc(121)] ActionProcessor: Aborting processing due to failure.
10-23 00:35:06.650 1030 1030 I update_engine: [1023/003506.650589:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 00:35:06.650 1030 1030 I update_engine: [1023/003506.650633:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, vendor_b] from device mapper
10-23 00:35:06.866 1030 1030 I update_engine: [1023/003506.866245:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 00:35:07.294 1030 1030 I update_engine: [1023/003507.294241:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 00:35:07.726 1030 1030 I update_engine: [1023/003507.726345:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 00:35:07.733 1030 1030 I update_engine: [1023/003507.733044:INFO:metrics_reporter_android.cc(29)] uploading 3 to histogram for metric ota_update_engine_attempt_number
10-23 00:35:07.733 1030 1030 I update_engine: [1023/003507.733357:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 00:35:07.733 1030 1030 I update_engine: [1023/003507.733475:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 00:35:07.733 1030 1030 I update_engine: [1023/003507.733625:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 00:35:07.733 1030 1030 I update_engine: [1023/003507.733763:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 00:35:07.733 1030 1030 I update_engine: [1023/003507.733901:INFO:metrics_reporter_android.cc(29)] uploading 5 to histogram for metric ota_update_engine_attempt_result
10-23 00:35:07.734 1030 1030 I update_engine: [1023/003507.734082:INFO:metrics_reporter_android.cc(29)] uploading 7 to histogram for metric ota_update_engine_attempt_error_code
10-23 00:35:07.734 1030 1030 I update_engine: [1023/003507.734428:INFO:metrics_reporter_android.cc(29)] uploading 1505 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 00:35:07.735 3074 4585 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 00:35:07.834 3074 4585 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 00:35:07.875 3074 4765 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 00:35:07.937 3074 15596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x412, -1.000).
10-23 00:35:08.139 3074 15596 I SystemUpdate: [Execution,SynchronizedUpdateEngine] stop()
10-23 00:35:08.139 3074 15596 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 6.52 seconds.
10-23 00:35:08.152 3074 15596 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 00:51:34.684 3074 16420 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 00:51:34.695 3074 16421 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 00:51:34.709 3074 16421 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 00:51:34.725 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 00:52:07.753 3074 16449 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 00:52:07.760 3074 16450 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 00:52:07.777 3074 16450 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.02 seconds.
10-23 01:01:25.827 3074 16891 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:01:25.834 3074 16892 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:01:25.853 3074 16892 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:01:25.867 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 01:01:25.876 3074 16892 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:01:25.882 3074 16891 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:01:25.898 3074 16891 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:01:26.044 3074 16891 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:01:26.057 3074 16892 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:01:26.095 3074 16892 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:01:26.166 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 01:01:58.654 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x000, isSetupWizard=false.
10-23 01:01:58.686 3074 16921 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 102.
10-23 01:01:58.689 3074 16921 I SystemUpdate: [Execution,ExecutionManager] Finishing the current execution.
10-23 01:01:58.700 3074 16921 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 01:01:58.713 3074 16924 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:01:58.715 3074 5477 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: AutomaticUpdateFlagChanged.
10-23 01:01:58.740 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action pending executed for 0.02 seconds.
10-23 01:01:58.764 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
10-23 01:01:58.794 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x017, isSetupWizard=false.
10-23 01:02:01.685 3074 16924 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_header.bin with size 24.
10-23 01:02:03.891 3074 16924 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_metadata.bin with size 105160.
10-23 01:02:04.010 3074 16924 I SystemUpdate: [Execution,UpdateEngineDelegate] verifyPayloadMetadata()
10-23 01:02:04.011 1030 1030 I update_engine: [1023/010204.011357:INFO:binder_service_android.cc(145)] Received a request of verifying payload metadata in /data/ota_package/payload_metadata.bin.
10-23 01:02:04.012 1030 1030 I update_engine: [1023/010204.012910:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 01:02:04.015 1030 1030 I update_engine: [1023/010204.014958:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 01:02:04.015 1030 1030 I update_engine: [1023/010204.015095:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 01:02:05.956 3074 16924 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 554.
10-23 01:02:06.018 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action pre-download-validate executed for 7.27 seconds.
10-23 01:02:06.067 3074 16924 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 01:02:06.071 3074 4236 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 01:02:06.074 3074 4395 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 01:02:06.075 3074 4398 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 01:02:06.083 3074 16979 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 01:02:06.138 3074 16979 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 01:02:06.188 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 01:02:06.227 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x002, isSetupWizard=false.
10-23 01:02:08.812 3074 16924 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_properties.txt with size 156.
10-23 01:02:08.903 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 01:02:10.874 3074 16924 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/care_map.pb with size 561.
10-23 01:02:10.965 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 01:02:13.541 3074 16924 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 554.
10-23 01:02:13.554 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 7.53 seconds.
10-23 01:02:13.563 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x003, -1.000).
10-23 01:02:13.607 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x003, isSetupWizard=false.
10-23 01:02:13.768 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action streaming-process-package executed for 0.21 seconds.
10-23 01:02:13.776 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action ab-update-prep executed for 0.01 seconds.
10-23 01:02:13.843 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
10-23 01:02:13.881 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x111, isSetupWizard=false.
10-23 01:02:14.010 3074 16924 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 01:02:14.013 3074 4236 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 01:02:14.017 3074 4398 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 01:02:14.018 3074 4395 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 01:02:14.027 3074 16979 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 01:02:14.126 3074 16979 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 01:02:14.199 3074 16924 I SystemUpdate: [Execution,SynchronizedUpdateEngine] start(ayce{url=https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload_binary_file.offset=1260, payload_binary_file.length=1576938951, properties=[FILE_HASH=yNdMzP4nHXPaSqS1D97Atji7R6JYwNRdRCyYebkY9+s=, FILE_SIZE=1576938951, METADATA_HASH=s4Rs4XtVL4Bzt4PV1NVNJoZWvqwa63LQMnGYpc4WPi0=, METADATA_SIZE=104637]}, additionalProperties.size=4)
10-23 01:02:14.200 3074 16924 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 01:02:14.203 1030 1030 I update_engine: [1023/010214.203728:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 01:02:14.204 1030 1030 I update_engine: [1023/010214.204087:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: false, run_post_install: true, is_rollback: false, write_verity: false
10-23 01:02:14.204 1030 1030 I update_engine: [1023/010214.204796:INFO:metrics_utils.cc(357)] Payload Attempt Number = 4
10-23 01:02:14.205 1030 1030 I update_engine: [1023/010214.205857:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:53:37 GMT
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206169:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 1:30:48 GMT
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206253:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206536:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206601:INFO:update_boot_flags_action.cc(36)] Already updated boot flags. Skipping.
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206652:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206701:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206755:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206800:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 01:02:14.206 1030 1030 I update_engine: [1023/010214.206859:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: false, run_post_install: true, is_rollback: false, write_verity: false
10-23 01:02:14.207 1030 1030 I update_engine: [1023/010214.206977:INFO:prefs.cc(122)] update-state-payload-index not present in /data/misc/update_engine/prefs
10-23 01:02:14.207 1030 1030 I update_engine: [1023/010214.207030:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 01:02:14.209 3074 3152 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x002.
10-23 01:02:14.247 1030 1030 I update_engine: [1023/010214.247634:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 01:02:14.247 1030 1030 I update_engine: [1023/010214.247820:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 1260+105160
10-23 01:02:14.248 1030 1030 I update_engine: [1023/010214.248003:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
10-23 01:02:14.248 1030 1030 I update_engine: [1023/010214.248215:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
10-23 01:02:14.248 1030 1030 I update_engine: [1023/010214.248321:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
10-23 01:02:15.374 1030 1030 I update_engine: [1023/010215.373947:INFO:certificate_checker.cc(185)] Certificate changed from 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD to 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0.
10-23 01:02:16.342 1030 1030 I update_engine: [1023/010216.342382:INFO:certificate_checker.cc(185)] Certificate changed from 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0 to 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD.
10-23 01:02:17.663 1030 1030 I update_engine: [1023/010217.663457:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 01:02:17.667 3074 3152 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 01:02:17.720 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.000011.
10-23 01:02:17.768 1030 1030 I update_engine: [1023/010217.768493:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 01:02:17.769 1030 1030 I update_engine: [1023/010217.768962:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 01:02:17.769 1030 1030 I update_engine: [1023/010217.769906:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 01:02:17.772 1030 1030 I update_engine: [1023/010217.772004:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 01:02:17.772 1030 1030 I update_engine: [1023/010217.772148:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 01:02:17.784 1030 1030 I update_engine: [1023/010217.784381:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 01:02:17.830 1030 1030 I update_engine: [1023/010217.830109:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 01:02:17.831 3074 3152 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 01:02:17.833 1030 1030 I update_engine: [1023/010217.833044:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:17.895 1030 1030 I update_engine: [1023/010217.895267:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped vendor_b to device mapper (force_writable = 1); device path at /dev/block/dm-1
10-23 01:02:17.898 1030 1030 I update_engine: [1023/010217.898721:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:17.899 1030 1030 I update_engine: [1023/010217.899397:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 01:02:17.900 1030 1030 I update_engine: [1023/010217.900139:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:17.902 1030 1030 I update_engine: [1023/010217.902268:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:17.964 1030 1030 I update_engine: [1023/010217.963862:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-3
10-23 01:02:17.968 1030 1030 I update_engine: [1023/010217.968073:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:17.969 1030 1030 I update_engine: [1023/010217.969437:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 01:02:17.970 1030 1030 I update_engine: [1023/010217.970355:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:17.973 1030 1030 I update_engine: [1023/010217.973036:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:17.973 1030 1030 I update_engine: [1023/010217.973723:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 01:02:17.974 1030 1030 I update_engine: [1023/010217.974695:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:17.977 1030 1030 I update_engine: [1023/010217.977492:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.041 1030 1030 I update_engine: [1023/010218.041644:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-5
10-23 01:02:18.047 1030 1030 I update_engine: [1023/010218.046932:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.047 1030 1030 I update_engine: [1023/010218.047931:INFO:boot_control_android.cc(140)] system_ext_b is not in super partition metadata.
10-23 01:02:18.049 1030 1030 I update_engine: [1023/010218.049195:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.049 1030 1030 E update_engine: [1023/010218.049451:ERROR:boot_control_android.cc(215)] Device file /dev/block/bootdevice/by-name/system_ext_b does not exist.
10-23 01:02:18.052 1030 1030 I update_engine: [1023/010218.052586:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.053 1030 1030 I update_engine: [1023/010218.053233:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 01:02:18.054 1030 1030 I update_engine: [1023/010218.054372:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.057 1030 1030 I update_engine: [1023/010218.057804:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.058 1030 1030 I update_engine: [1023/010218.058706:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 01:02:18.059 1030 1030 I update_engine: [1023/010218.059858:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.063 1030 1030 I update_engine: [1023/010218.063621:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.064 1030 1030 I update_engine: [1023/010218.064438:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 01:02:18.065 1030 1030 I update_engine: [1023/010218.065766:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.069 1030 1030 I update_engine: [1023/010218.069370:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.070 1030 1030 I update_engine: [1023/010218.070330:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 01:02:18.072 1030 1030 I update_engine: [1023/010218.072281:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.075 1030 1030 I update_engine: [1023/010218.075773:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.076 1030 1030 I update_engine: [1023/010218.076574:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 01:02:18.077 1030 1030 I update_engine: [1023/010218.077514:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.080 1030 1030 I update_engine: [1023/010218.080371:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.081 1030 1030 I update_engine: [1023/010218.081036:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 01:02:18.082 1030 1030 I update_engine: [1023/010218.082026:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.085 1030 1030 I update_engine: [1023/010218.085074:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.086 1030 1030 I update_engine: [1023/010218.085952:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 01:02:18.086 1030 1030 I update_engine: [1023/010218.086883:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.089 1030 1030 I update_engine: [1023/010218.089794:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.090 1030 1030 I update_engine: [1023/010218.090334:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 01:02:18.091 1030 1030 I update_engine: [1023/010218.091241:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.094 1030 1030 I update_engine: [1023/010218.094199:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.094 1030 1030 I update_engine: [1023/010218.094818:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 01:02:18.095 1030 1030 I update_engine: [1023/010218.095715:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.097 1030 1030 I update_engine: [1023/010218.097796:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.098 1030 1030 I update_engine: [1023/010218.098334:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 01:02:18.099 1030 1030 I update_engine: [1023/010218.099079:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.101 1030 1030 I update_engine: [1023/010218.100968:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.101 1030 1030 I update_engine: [1023/010218.101386:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 01:02:18.102 1030 1030 I update_engine: [1023/010218.102081:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.104 1030 1030 I update_engine: [1023/010218.104001:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:02:18.104 1030 1030 I update_engine: [1023/010218.104432:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 01:02:18.105 1030 1030 I update_engine: [1023/010218.105073:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:02:18.105 1030 1030 E update_engine: [1023/010218.105209:ERROR:delta_performer.cc(933)] Unable to determine all the partition devices.
10-23 01:02:18.105 1030 1030 E update_engine: [1023/010218.105355:ERROR:download_action.cc(336)] Error ErrorCode::kInstallDeviceOpenError (7) in DeltaPerformer's Write method when processing the received payload -- Terminating processing
10-23 01:02:18.105 1030 1030 I update_engine: [1023/010218.105468:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
10-23 01:02:18.110 1030 1030 I update_engine: [1023/010218.110150:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 01:02:18.111 1030 1030 I update_engine: [1023/010218.111111:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
10-23 01:02:18.111 1030 1030 I update_engine: [1023/010218.111211:INFO:multi_range_http_fetcher.cc(131)] Terminating.
10-23 01:02:18.126 1030 1030 I update_engine: [1023/010218.126766:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kInstallDeviceOpenError
10-23 01:02:18.127 1030 1030 I update_engine: [1023/010218.127170:INFO:action_processor.cc(121)] ActionProcessor: Aborting processing due to failure.
10-23 01:02:18.127 1030 1030 I update_engine: [1023/010218.127273:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 01:02:18.127 1030 1030 I update_engine: [1023/010218.127345:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, vendor_b] from device mapper
10-23 01:02:18.343 1030 1030 I update_engine: [1023/010218.343700:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 01:02:18.764 1030 1030 I update_engine: [1023/010218.763944:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 01:02:19.181 1030 1030 I update_engine: [1023/010219.181199:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 01:02:19.182 1030 1030 I update_engine: [1023/010219.182640:INFO:metrics_reporter_android.cc(29)] uploading 4 to histogram for metric ota_update_engine_attempt_number
10-23 01:02:19.182 1030 1030 I update_engine: [1023/010219.182795:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 01:02:19.182 1030 1030 I update_engine: [1023/010219.182912:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 01:02:19.183 1030 1030 I update_engine: [1023/010219.183057:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 01:02:19.183 1030 1030 I update_engine: [1023/010219.183170:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 01:02:19.183 1030 1030 I update_engine: [1023/010219.183281:INFO:metrics_reporter_android.cc(29)] uploading 5 to histogram for metric ota_update_engine_attempt_result
10-23 01:02:19.183 1030 1030 I update_engine: [1023/010219.183392:INFO:metrics_reporter_android.cc(29)] uploading 7 to histogram for metric ota_update_engine_attempt_error_code
10-23 01:02:19.183 1030 1030 I update_engine: [1023/010219.183626:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 01:02:19.190 3074 3152 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 01:02:19.244 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.000000.
10-23 01:02:19.359 3074 3152 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 01:02:19.409 3074 3152 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 01:02:19.456 3074 16924 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x412, -1.000).
10-23 01:02:19.497 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 01:02:19.654 3074 16924 I SystemUpdate: [Execution,SynchronizedUpdateEngine] stop()
10-23 01:02:19.654 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 5.87 seconds.
10-23 01:02:19.671 3074 16924 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:02:52.926 3074 17028 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:02:52.940 3074 17036 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:02:52.972 3074 17036 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:07:57.992 3074 17285 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:07:58.002 3074 17284 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:07:58.026 3074 17284 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.02 seconds.
10-23 01:07:58.052 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 01:08:14.947 3074 17297 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:08:14.958 3074 17298 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:08:14.987 3074 17298 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.02 seconds.
10-23 01:08:45.608 3074 17540 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:08:45.618 3074 17541 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:08:45.632 3074 17541 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:08:45.649 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 01:09:11.018 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x000, isSetupWizard=false.
10-23 01:09:11.047 3074 17555 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 102.
10-23 01:09:11.055 3074 17555 I SystemUpdate: [Execution,ExecutionManager] Finishing the current execution.
10-23 01:09:11.067 3074 3331 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: AutomaticUpdateFlagChanged.
10-23 01:09:11.068 3074 17555 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 01:09:11.080 3074 17557 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:09:11.101 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action pending executed for 0.01 seconds.
10-23 01:09:11.130 3074 17557 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
10-23 01:09:11.156 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x017, isSetupWizard=false.
10-23 01:09:13.022 3074 17557 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_header.bin with size 24.
10-23 01:09:14.596 3074 17557 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_metadata.bin with size 105160.
10-23 01:09:14.678 3074 17557 I SystemUpdate: [Execution,UpdateEngineDelegate] verifyPayloadMetadata()
10-23 01:09:14.680 1030 1030 I update_engine: [1023/010914.679979:INFO:binder_service_android.cc(145)] Received a request of verifying payload metadata in /data/ota_package/payload_metadata.bin.
10-23 01:09:14.681 1030 1030 I update_engine: [1023/010914.681104:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 01:09:14.682 1030 1030 I update_engine: [1023/010914.682347:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 01:09:14.682 1030 1030 I update_engine: [1023/010914.682500:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 01:09:17.067 3074 17557 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 554.
10-23 01:09:17.150 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action pre-download-validate executed for 6.05 seconds.
10-23 01:09:17.203 3074 17557 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 01:09:17.208 3074 4236 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 01:09:17.210 3074 4395 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 01:09:17.212 3074 4398 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 01:09:17.218 3074 17558 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 01:09:17.294 3074 17558 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 01:09:17.349 3074 17557 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 01:09:17.380 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x002, isSetupWizard=false.
10-23 01:09:19.941 3074 17557 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_properties.txt with size 156.
10-23 01:09:20.023 3074 17557 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 01:09:22.092 3074 17557 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/care_map.pb with size 561.
10-23 01:09:22.185 3074 17557 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 01:09:24.118 3074 17557 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 554.
10-23 01:09:24.125 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 6.97 seconds.
10-23 01:09:24.133 3074 17557 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x003, -1.000).
10-23 01:09:24.172 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x003, isSetupWizard=false.
10-23 01:09:24.330 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action streaming-process-package executed for 0.20 seconds.
10-23 01:09:24.334 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action ab-update-prep executed for 0.00 seconds.
10-23 01:09:24.395 3074 17557 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 01:09:24.435 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x413, isSetupWizard=false.
10-23 01:09:24.565 3074 17557 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 01:09:24.570 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.23 seconds.
10-23 01:09:24.584 3074 17557 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 01:09:56.736 3074 17604 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:09:56.743 3074 17605 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:09:56.795 3074 17605 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 01:09:56.900 3074 17605 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 01:09:56.903 3074 17605 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.16 seconds.
10-23 01:09:56.918 3074 17605 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 01:09:57.884 3074 17605 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:09:57.891 3074 17604 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:09:57.924 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x413, isSetupWizard=false.
10-23 01:09:57.992 3074 17604 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 01:09:58.115 3074 17604 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 01:09:58.117 3074 17604 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.22 seconds.
10-23 01:09:58.128 3074 17604 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 01:10:02.815 3074 17604 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 109.
10-23 01:10:02.821 3074 17605 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:10:02.882 3074 17605 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
10-23 01:10:02.915 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x111, isSetupWizard=false.
10-23 01:10:03.034 3074 17605 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 01:10:03.038 3074 4236 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 01:10:03.042 3074 4398 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 01:10:03.043 3074 4395 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 01:10:03.050 3074 17604 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 01:10:03.136 3074 17604 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 01:10:03.211 3074 17605 I SystemUpdate: [Execution,SynchronizedUpdateEngine] start(ayce{url=https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload_binary_file.offset=1260, payload_binary_file.length=1576938951, properties=[FILE_HASH=yNdMzP4nHXPaSqS1D97Atji7R6JYwNRdRCyYebkY9+s=, FILE_SIZE=1576938951, METADATA_HASH=s4Rs4XtVL4Bzt4PV1NVNJoZWvqwa63LQMnGYpc4WPi0=, METADATA_SIZE=104637]}, additionalProperties.size=4)
10-23 01:10:03.212 3074 17605 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 01:10:03.215 1030 1030 I update_engine: [1023/011003.215288:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 01:10:03.215 1030 1030 I update_engine: [1023/011003.215623:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: false, run_post_install: true, is_rollback: false, write_verity: false
10-23 01:10:03.217 1030 1030 I update_engine: [1023/011003.217101:INFO:metrics_utils.cc(357)] Payload Attempt Number = 5
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218090:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 1:01:26 GMT
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218361:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 1:38:37 GMT
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218430:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218770:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218837:INFO:update_boot_flags_action.cc(36)] Already updated boot flags. Skipping.
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218900:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 01:10:03.218 1030 1030 I update_engine: [1023/011003.218950:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 01:10:03.219 1030 1030 I update_engine: [1023/011003.219014:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 01:10:03.219 1030 1030 I update_engine: [1023/011003.219069:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 01:10:03.219 1030 1030 I update_engine: [1023/011003.219130:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: false, run_post_install: true, is_rollback: false, write_verity: false
10-23 01:10:03.219 1030 1030 I update_engine: [1023/011003.219229:INFO:prefs.cc(122)] update-state-payload-index not present in /data/misc/update_engine/prefs
10-23 01:10:03.219 1030 1030 I update_engine: [1023/011003.219279:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 01:10:03.221 3074 6749 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x002.
10-23 01:10:03.266 1030 1030 I update_engine: [1023/011003.266597:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 01:10:03.266 1030 1030 I update_engine: [1023/011003.266778:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 1260+105160
10-23 01:10:03.266 1030 1030 I update_engine: [1023/011003.266916:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
10-23 01:10:03.267 1030 1030 I update_engine: [1023/011003.267079:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
10-23 01:10:03.267 1030 1030 I update_engine: [1023/011003.267165:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
10-23 01:10:04.395 1030 1030 I update_engine: [1023/011004.395329:INFO:certificate_checker.cc(185)] Certificate changed from 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD to 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0.
10-23 01:10:04.419 3074 4395 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 01:10:04.429 3074 17604 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 01:10:05.374 1030 1030 I update_engine: [1023/011005.374414:INFO:certificate_checker.cc(185)] Certificate changed from 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0 to 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD.
10-23 01:10:06.453 1030 1030 I update_engine: [1023/011006.453469:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 01:10:06.458 3074 6749 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 01:10:06.508 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.000011.
10-23 01:10:06.581 1030 1030 I update_engine: [1023/011006.581791:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 01:10:06.582 1030 1030 I update_engine: [1023/011006.582157:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 01:10:06.582 1030 1030 I update_engine: [1023/011006.582486:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 01:10:06.583 1030 1030 I update_engine: [1023/011006.583736:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 01:10:06.583 1030 1030 I update_engine: [1023/011006.583836:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 01:10:06.591 1030 1030 I update_engine: [1023/011006.591203:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 01:10:06.610 3074 6749 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 01:10:06.623 1030 1030 I update_engine: [1023/011006.623358:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 01:10:06.625 1030 1030 I update_engine: [1023/011006.625842:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.685 1030 1030 I update_engine: [1023/011006.685323:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped vendor_b to device mapper (force_writable = 1); device path at /dev/block/dm-1
10-23 01:10:06.688 1030 1030 I update_engine: [1023/011006.688029:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.688 1030 1030 I update_engine: [1023/011006.688783:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 01:10:06.689 1030 1030 I update_engine: [1023/011006.689497:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.691 1030 1030 I update_engine: [1023/011006.691366:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.750 1030 1030 I update_engine: [1023/011006.750052:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-3
10-23 01:10:06.753 1030 1030 I update_engine: [1023/011006.753525:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.754 1030 1030 I update_engine: [1023/011006.754300:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 01:10:06.755 1030 1030 I update_engine: [1023/011006.755180:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.757 1030 1030 I update_engine: [1023/011006.757773:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.758 1030 1030 I update_engine: [1023/011006.758400:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 01:10:06.759 1030 1030 I update_engine: [1023/011006.759490:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.761 1030 1030 I update_engine: [1023/011006.761829:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.821 1030 1030 I update_engine: [1023/011006.821334:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-5
10-23 01:10:06.826 1030 1030 I update_engine: [1023/011006.826503:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.827 1030 1030 I update_engine: [1023/011006.827279:INFO:boot_control_android.cc(140)] system_ext_b is not in super partition metadata.
10-23 01:10:06.828 1030 1030 I update_engine: [1023/011006.828307:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.828 1030 1030 E update_engine: [1023/011006.828657:ERROR:boot_control_android.cc(215)] Device file /dev/block/bootdevice/by-name/system_ext_b does not exist.
10-23 01:10:06.831 1030 1030 I update_engine: [1023/011006.831475:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.832 1030 1030 I update_engine: [1023/011006.832402:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 01:10:06.833 1030 1030 I update_engine: [1023/011006.833437:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.836 1030 1030 I update_engine: [1023/011006.836288:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.836 1030 1030 I update_engine: [1023/011006.836924:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 01:10:06.837 1030 1030 I update_engine: [1023/011006.837899:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.841 1030 1030 I update_engine: [1023/011006.841006:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.841 1030 1030 I update_engine: [1023/011006.841619:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 01:10:06.842 1030 1030 I update_engine: [1023/011006.842631:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.845 1030 1030 I update_engine: [1023/011006.845204:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.846 1030 1030 I update_engine: [1023/011006.846056:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 01:10:06.847 1030 1030 I update_engine: [1023/011006.847005:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.849 1030 1030 I update_engine: [1023/011006.849863:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.850 1030 1030 I update_engine: [1023/011006.850738:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 01:10:06.851 1030 1030 I update_engine: [1023/011006.851725:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.854 1030 1030 I update_engine: [1023/011006.854696:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.855 1030 1030 I update_engine: [1023/011006.855406:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 01:10:06.856 1030 1030 I update_engine: [1023/011006.856203:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.858 1030 1030 I update_engine: [1023/011006.858865:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.859 1030 1030 I update_engine: [1023/011006.859337:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 01:10:06.860 1030 1030 I update_engine: [1023/011006.860185:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.862 1030 1030 I update_engine: [1023/011006.862366:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.863 1030 1030 I update_engine: [1023/011006.863130:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 01:10:06.863 1030 1030 I update_engine: [1023/011006.863922:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.866 1030 1030 I update_engine: [1023/011006.866239:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.866 1030 1030 I update_engine: [1023/011006.866702:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 01:10:06.867 1030 1030 I update_engine: [1023/011006.867382:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.869 1030 1030 I update_engine: [1023/011006.869401:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.869 1030 1030 I update_engine: [1023/011006.869748:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 01:10:06.870 1030 1030 I update_engine: [1023/011006.870399:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.872 1030 1030 I update_engine: [1023/011006.872158:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.872 1030 1030 I update_engine: [1023/011006.872544:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 01:10:06.873 1030 1030 I update_engine: [1023/011006.873093:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.874 1030 1030 I update_engine: [1023/011006.874751:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 01:10:06.875 1030 1030 I update_engine: [1023/011006.875106:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 01:10:06.875 1030 1030 I update_engine: [1023/011006.875718:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 01:10:06.875 1030 1030 E update_engine: [1023/011006.875845:ERROR:delta_performer.cc(933)] Unable to determine all the partition devices.
10-23 01:10:06.875 1030 1030 E update_engine: [1023/011006.875911:ERROR:download_action.cc(336)] Error ErrorCode::kInstallDeviceOpenError (7) in DeltaPerformer's Write method when processing the received payload -- Terminating processing
10-23 01:10:06.876 1030 1030 I update_engine: [1023/011006.876001:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
10-23 01:10:06.880 1030 1030 I update_engine: [1023/011006.880175:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 01:10:06.880 1030 1030 I update_engine: [1023/011006.880415:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
10-23 01:10:06.880 1030 1030 I update_engine: [1023/011006.880468:INFO:multi_range_http_fetcher.cc(131)] Terminating.
10-23 01:10:06.893 1030 1030 I update_engine: [1023/011006.893206:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kInstallDeviceOpenError
10-23 01:10:06.893 1030 1030 I update_engine: [1023/011006.893591:INFO:action_processor.cc(121)] ActionProcessor: Aborting processing due to failure.
10-23 01:10:06.893 1030 1030 I update_engine: [1023/011006.893678:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 01:10:06.893 1030 1030 I update_engine: [1023/011006.893748:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, vendor_b] from device mapper
10-23 01:10:07.154 1030 1030 I update_engine: [1023/011007.154570:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 01:10:07.574 1030 1030 I update_engine: [1023/011007.573877:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 01:10:07.990 1030 1030 I update_engine: [1023/011007.990701:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 01:10:07.993 1030 1030 I update_engine: [1023/011007.993334:INFO:metrics_reporter_android.cc(29)] uploading 5 to histogram for metric ota_update_engine_attempt_number
10-23 01:10:07.993 1030 1030 I update_engine: [1023/011007.993900:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 01:10:07.994 1030 1030 I update_engine: [1023/011007.994320:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 01:10:07.994 1030 1030 I update_engine: [1023/011007.994738:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 01:10:07.995 1030 1030 I update_engine: [1023/011007.995041:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 01:10:07.995 1030 1030 I update_engine: [1023/011007.995367:INFO:metrics_reporter_android.cc(29)] uploading 5 to histogram for metric ota_update_engine_attempt_result
10-23 01:10:07.995 1030 1030 I update_engine: [1023/011007.995603:INFO:metrics_reporter_android.cc(29)] uploading 7 to histogram for metric ota_update_engine_attempt_error_code
10-23 01:10:07.996 1030 1030 I update_engine: [1023/011007.996522:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 01:10:08.000 3074 6749 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 01:10:08.055 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.000000.
10-23 01:10:08.166 3074 6749 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 01:10:08.213 3074 6749 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 01:10:08.262 3074 17605 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x412, -1.000).
10-23 01:10:08.305 9899 9899 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x412, isSetupWizard=false.
10-23 01:10:08.462 3074 17605 I SystemUpdate: [Execution,SynchronizedUpdateEngine] stop()
10-23 01:10:08.463 3074 17605 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 5.64 seconds.
10-23 01:10:08.488 3074 17605 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.02 seconds.
10-23 01:12:29.192 3074 17835 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 01:12:29.199 3074 17836 I SystemUpdate: [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 01:12:29.208 3074 17836 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 01:17:35.528 1030 1030 I update_engine: [1023/011735.527954:INFO:main.cc(198)] A/B Update Engine terminating with exit code 0
10-23 01:17:35.528 1030 1030 I update_engine: [1023/011735.528329:INFO:dynamic_partition_control_android.cc(151)] Destroying [] from device mapper
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment