Skip to content

Instantly share code, notes, and snippets.

@nooriro

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

Created October 23, 2020 08:10
Show Gist options
  • Save nooriro/be46a571aa69ce1c00c369ee901e0340 to your computer and use it in GitHub Desktop.
Save nooriro/be46a571aa69ce1c00c369ee901e0340 to your computer and use it in GitHub Desktop.
Download ZIP
sunfish update log (SUCCEEDED LOG, "Automatic system updates" test) / 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-1400.txt
--------- beginning of system
--------- beginning of main
10-23 13:29:25.993 7088 10483 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 11.
10-23 13:29:26.296 7088 11421 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 13:29:26.323 7088 11421 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:29:50.184 7088 11904 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.TIME_SET flg=0x1000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver (has extras) }.
10-23 13:29:50.193 7088 11903 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 6.
10-23 13:29:50.196 7088 11896 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 13:29:50.197 7088 11896 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:29:50.330 7088 11904 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.TIME_SET flg=0x1000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver (has extras) }.
10-23 13:29:50.343 7088 11903 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 6.
10-23 13:29:50.347 7088 11904 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 13:29:50.354 7088 11904 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:32:03.083 7088 12915 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.USER_PRESENT flg=0x24200010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 13:32:03.146 7088 12915 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 11.
10-23 13:32:03.153 7088 12916 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 13:32:03.163 7088 12916 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:32:39.611 7088 13002 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.gservices.intent.action.GSERVICES_CHANGED flg=0x1000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver }.
10-23 13:32:39.662 7088 13002 I SystemUpdate: [Control,InstallationControl] Update URL changed from "" to "https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip".
10-23 13:32:40.172 7088 13002 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:32:40.179 7088 13002 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 102.
10-23 13:32:40.180 7088 13002 I SystemUpdate: [Execution,ExecutionManager] Finishing the current execution.
10-23 13:32:40.278 7088 13002 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 13:32:40.297 7088 14078 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 13:32:40.429 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action pending executed for 0.01 seconds.
10-23 13:32:40.445 7088 14078 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
10-23 13:32:41.357 7088 14079 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.gservices.intent.action.GSERVICES_CHANGED flg=0x1000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver }.
10-23 13:32:41.369 7088 14184 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:32:43.358 7088 14078 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_header.bin with size 24.
10-23 13:32:44.569 7088 14078 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_metadata.bin with size 105160.
10-23 13:32:44.698 7088 14078 I SystemUpdate: [Execution,UpdateEngineDelegate] verifyPayloadMetadata()
10-23 13:32:44.699 1031 1031 I update_engine: [1023/133244.698910:INFO:binder_service_android.cc(145)] Received a request of verifying payload metadata in /data/ota_package/payload_metadata.bin.
10-23 13:32:44.704 1031 1031 I update_engine: [1023/133244.703984:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:32:44.707 1031 1031 I update_engine: [1023/133244.707530:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:32:44.707 1031 1031 I update_engine: [1023/133244.707746:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 13:32:46.116 7088 14078 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/metadata with size 554.
10-23 13:32:46.128 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action pre-download-validate executed for 5.70 seconds.
10-23 13:32:46.203 7088 14078 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 13:32:46.290 7088 14078 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 13:32:46.293 7088 7957 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 13:32:46.306 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:32:46.306 7088 7340 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 13:32:46.319 7088 14197 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:32:46.334 7088 14197 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 13:32:47.588 7088 14078 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_properties.txt with size 156.
10-23 13:32:49.623 7088 14078 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/care_map.pb with size 561.
10-23 13:32:51.542 7088 14078 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/metadata with size 554.
10-23 13:32:51.549 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 5.41 seconds.
10-23 13:32:51.556 7088 14078 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x003, -1.000).
10-23 13:32:51.620 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action streaming-process-package executed for 0.07 seconds.
10-23 13:32:51.672 7088 14078 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 13:32:51.720 7088 14078 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:32:51.725 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.10 seconds.
10-23 13:32:51.743 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.02 seconds.
10-23 13:32:51.752 7088 14078 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 13:32:51.782 7088 14078 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:32:51.787 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.04 seconds.
10-23 13:32:51.799 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 13:32:51.805 7088 14078 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 13:32:51.852 7088 14078 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:32:51.854 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.05 seconds.
10-23 13:32:51.860 7088 14078 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.00 seconds.
10-23 13:33:01.529 7088 15424 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.android.gms.phenotype.COMMITTED flg=0x10 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.GmsIntentOperationService (has extras) }.
10-23 13:33:01.565 7088 14197 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:33:01.568 7088 15425 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 13:33:01.606 7088 15425 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 13:33:01.653 7088 15425 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:33:01.659 7088 15425 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.09 seconds.
10-23 13:33:01.678 7088 15425 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 13:33:11.364 7088 15994 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.android.gms.phenotype.COMMITTED flg=0x10 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.GmsIntentOperationService (has extras) }.
10-23 13:33:11.387 7088 15518 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:33:11.391 7088 16001 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 13:33:11.422 7088 16001 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 13:33:11.465 7088 14204 I SystemUpdate: [Control,ChimeraGcmTaskService] Task started with tag: DeviceIdle.
10-23 13:33:11.467 7088 16001 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:33:11.467 7088 14204 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:33:11.471 7088 16001 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.08 seconds.
10-23 13:33:11.484 7088 15994 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 5.
10-23 13:33:11.489 7088 16001 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.02 seconds.
10-23 13:33:11.498 7088 16001 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 13:33:11.536 7088 16001 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
10-23 13:33:11.580 7088 16001 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 13:33:11.584 7088 7957 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 13:33:11.589 7088 14200 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:33:11.595 7088 10601 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 13:33:11.605 7088 15994 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:33:11.631 7088 15994 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 13:33:11.652 7088 16001 I SystemUpdate: [Execution,SynchronizedUpdateEngine] start(awxd{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=3)
10-23 13:33:11.654 7088 16001 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 13:33:11.660 1031 1031 I update_engine: [1023/133311.660378:INFO:prefs.cc(122)] update-state-next-operation not present in /data/misc/update_engine/prefs
10-23 13:33:11.664 1031 1031 I update_engine: [1023/133311.664638:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 13:33:11.664 1031 1031 I update_engine: [1023/133311.664834:INFO:install_plan.cc(83)] InstallPlan: new_update, 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: true, run_post_install: true, is_rollback: false, write_verity: true
10-23 13:33:11.665 1031 1031 I update_engine: [1023/133311.665523:INFO:metrics_utils.cc(349)] Number of Reboots during current update attempt = 0
10-23 13:33:11.665 1031 1031 I update_engine: [1023/133311.665743:INFO:metrics_utils.cc(357)] Payload Attempt Number = 1
10-23 13:33:11.666 1031 1031 I update_engine: [1023/133311.665905:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:05:07 GMT
10-23 13:33:11.666 1031 1031 I update_engine: [1023/133311.666675:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 0:05:07 GMT
10-23 13:33:11.666 1031 1031 I update_engine: [1023/133311.666721:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 13:33:11.667 1031 1031 I update_engine: [1023/133311.666999:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 13:33:11.667 1031 1031 I update_engine: [1023/133311.667039:INFO:update_boot_flags_action.cc(45)] Marking booted slot as good.
10-23 13:33:11.668 7088 7107 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x002.
10-23 13:33:11.692 1031 1031 I update_engine: [1023/133311.692071:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 13:33:11.692 1031 1031 I update_engine: [1023/133311.692247:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 13:33:11.692 1031 1031 I update_engine: [1023/133311.692370:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 13:33:11.692 1031 1031 I update_engine: [1023/133311.692403:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 13:33:11.692 1031 1031 I update_engine: [1023/133311.692452:INFO:install_plan.cc(83)] InstallPlan: new_update, 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: true, run_post_install: true, is_rollback: false, write_verity: true
10-23 13:33:11.692 1031 1031 I update_engine: [1023/133311.692499:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 13:33:11.715 1031 1031 I update_engine: [1023/133311.715658:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 13:33:11.715 1031 1031 I update_engine: [1023/133311.715837:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 1260+1576938951
10-23 13:33:11.715 1031 1031 I update_engine: [1023/133311.715971:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
10-23 13:33:11.718 1031 1031 I update_engine: [1023/133311.718729:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
10-23 13:33:11.718 1031 1031 I update_engine: [1023/133311.718944:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
10-23 13:33:12.908 1031 1031 I update_engine: [1023/133312.908191:INFO:prefs.cc(122)] update-server-cert-1-2 not present in /data/misc/update_engine/prefs
10-23 13:33:12.909 1031 1031 I update_engine: [1023/133312.909716:INFO:prefs.cc(122)] update-server-cert-1-1 not present in /data/misc/update_engine/prefs
10-23 13:33:12.910 1031 1031 I update_engine: [1023/133312.910549:INFO:prefs.cc(122)] update-server-cert-1-0 not present in /data/misc/update_engine/prefs
10-23 13:33:13.835 1031 1031 I update_engine: [1023/133313.835780:INFO:certificate_checker.cc(185)] Certificate changed from 2236258063ACF45D3624A0D641AE4DA4374B62AA6067D55E2494B63B970C5971 to 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD.
10-23 13:33:14.525 1031 1031 I update_engine: [1023/133314.524938:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 13:33:14.527 7088 7381 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 13:33:14.610 7088 7381 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:14.676 1031 1031 I update_engine: [1023/133314.675986:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 13:33:14.676 1031 1031 I update_engine: [1023/133314.676202:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:33:14.676 1031 1031 I update_engine: [1023/133314.676434:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:33:14.677 1031 1031 I update_engine: [1023/133314.677317:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:33:14.677 1031 1031 I update_engine: [1023/133314.677396:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 13:33:14.682 1031 1031 I update_engine: [1023/133314.682239:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 13:33:14.705 1031 1031 I update_engine: [1023/133314.704995:INFO:prefs.cc(122)] dynamic-partition-metadata-updated not present in /data/misc/update_engine/prefs
10-23 13:33:14.929 1031 1031 I update_engine: [1023/133314.929774:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 13:33:15.345 1031 1031 I update_engine: [1023/133315.345346:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 13:33:15.345 1031 1031 W update_engine: type=1400 audit(0.0:562): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350386:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350543:INFO:boot_control_android.cc(312)] Removing group google_dynamic_partitions_b
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350600:INFO:boot_control_android.cc(343)] Added group google_dynamic_partitions_b with size 4873781248
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350700:INFO:boot_control_android.cc(360)] Added partition system_b to group google_dynamic_partitions_b with size 820408320
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350770:INFO:boot_control_android.cc(360)] Added partition vendor_b to group google_dynamic_partitions_b with size 550678528
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350839:INFO:boot_control_android.cc(360)] Added partition product_b to group google_dynamic_partitions_b with size 2044289024
10-23 13:33:15.350 1031 1031 I update_engine: [1023/133315.350906:INFO:boot_control_android.cc(360)] Added partition system_ext_b to group google_dynamic_partitions_b with size 231182336
10-23 13:33:15.355 1031 1031 W update_engine: type=1400 audit(0.0:563): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.357 1031 1031 I update_engine: [1023/133315.357187:INFO:dynamic_partition_control_android.cc(228)] Copied metadata to slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.357 1031 1031 I update_engine: [1023/133315.357583:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 13:33:15.359 1031 1031 I update_engine: [1023/133315.359157:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.572 1031 1031 I update_engine: [1023/133315.572508: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 13:33:15.571 1031 1031 W update_engine: type=1400 audit(0.0:564): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.574 1031 1031 I update_engine: [1023/133315.574253:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.574 1031 1031 I update_engine: [1023/133315.574596:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 13:33:15.575 1031 1031 W update_engine: type=1400 audit(0.0:565): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.575 1031 1031 I update_engine: [1023/133315.575127:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.576 1031 1031 I update_engine: [1023/133315.576573:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.628 1031 1031 W update_engine: type=1400 audit(0.0:566): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.631 1031 1031 W update_engine: type=1400 audit(0.0:568): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.630 1031 1031 I update_engine: [1023/133315.630032:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-4
10-23 13:33:15.631 1031 1031 I update_engine: [1023/133315.631565:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.631 1031 1031 I update_engine: [1023/133315.631897:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 13:33:15.632 1031 1031 I update_engine: [1023/133315.632338:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.633 1031 1031 I update_engine: [1023/133315.633495:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.633 1031 1031 I update_engine: [1023/133315.633692:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 13:33:15.634 1031 1031 I update_engine: [1023/133315.634048:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.635 1031 1031 I update_engine: [1023/133315.635102:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.691 1031 1031 W update_engine: type=1400 audit(0.0:569): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:33:15.692 1031 1031 I update_engine: [1023/133315.692040:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-9
10-23 13:33:15.694 1031 1031 I update_engine: [1023/133315.694170:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.751 1031 1031 I update_engine: [1023/133315.751403:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_ext_b to device mapper (force_writable = 1); device path at /dev/block/dm-10
10-23 13:33:15.752 1031 1031 I update_engine: [1023/133315.752960:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.753 1031 1031 I update_engine: [1023/133315.753838:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 13:33:15.754 1031 1031 I update_engine: [1023/133315.754381:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.756 1031 1031 I update_engine: [1023/133315.756294:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.756 1031 1031 I update_engine: [1023/133315.756591:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 13:33:15.757 1031 1031 I update_engine: [1023/133315.756990:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.758 1031 1031 I update_engine: [1023/133315.758370:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.759 1031 1031 I update_engine: [1023/133315.759478:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 13:33:15.760 1031 1031 I update_engine: [1023/133315.760299:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.766 1031 1031 I update_engine: [1023/133315.766544:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.766 1031 1031 I update_engine: [1023/133315.766862:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 13:33:15.767 1031 1031 I update_engine: [1023/133315.767256:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.768 1031 1031 I update_engine: [1023/133315.768424:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.768 1031 1031 I update_engine: [1023/133315.768822:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 13:33:15.769 1031 1031 I update_engine: [1023/133315.769322:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.771 1031 1031 I update_engine: [1023/133315.771040:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.771 1031 1031 I update_engine: [1023/133315.771888:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 13:33:15.772 1031 1031 I update_engine: [1023/133315.772502:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.775 1031 1031 I update_engine: [1023/133315.775021:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.775 1031 1031 I update_engine: [1023/133315.775829:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 13:33:15.777 1031 1031 I update_engine: [1023/133315.777066:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.779 1031 1031 I update_engine: [1023/133315.779653:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.780 1031 1031 I update_engine: [1023/133315.780104:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 13:33:15.780 1031 1031 I update_engine: [1023/133315.780618:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.782 1031 1031 I update_engine: [1023/133315.782042:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.782 1031 1031 I update_engine: [1023/133315.782339:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 13:33:15.783 1031 1031 I update_engine: [1023/133315.783358:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.785 1031 1031 I update_engine: [1023/133315.784965:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.788 1031 1031 I update_engine: [1023/133315.788029:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 13:33:15.788 1031 1031 I update_engine: [1023/133315.788690:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.791 1031 1031 I update_engine: [1023/133315.791606:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.792 1031 1031 I update_engine: [1023/133315.792053:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 13:33:15.796 1031 1031 I update_engine: [1023/133315.796088:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.798 1031 1031 I update_engine: [1023/133315.798123:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:33:15.798 1031 1031 I update_engine: [1023/133315.798397:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 13:33:15.798 1031 1031 I update_engine: [1023/133315.798844:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:33:15.798 1031 1031 I update_engine: [1023/133315.798944:INFO:delta_performer.cc(450)] PartitionInfo new vendor sha256: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4= size: 550678528
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.798986:INFO:delta_performer.cc(450)] PartitionInfo new boot sha256: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU= size: 67108864
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799020:INFO:delta_performer.cc(450)] PartitionInfo new system sha256: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q= size: 820408320
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799053:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta sha256: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg= size: 8192
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799086:INFO:delta_performer.cc(450)] PartitionInfo new dtbo sha256: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c= size: 8388608
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799119:INFO:delta_performer.cc(450)] PartitionInfo new product sha256: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA= size: 2044289024
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799153:INFO:delta_performer.cc(450)] PartitionInfo new system_ext sha256: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM= size: 231182336
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799186:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta_system sha256: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA= size: 4096
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799219:INFO:delta_performer.cc(450)] PartitionInfo new abl sha256: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA= size: 1048576
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799252:INFO:delta_performer.cc(450)] PartitionInfo new aop sha256: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk= size: 167936
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799285:INFO:delta_performer.cc(450)] PartitionInfo new devcfg sha256: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE= size: 45056
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799348:INFO:delta_performer.cc(450)] PartitionInfo new hyp sha256: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w= size: 397312
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799383:INFO:delta_performer.cc(450)] PartitionInfo new keymaster sha256: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0= size: 241664
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799416:INFO:delta_performer.cc(450)] PartitionInfo new qupfw sha256: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w= size: 53248
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799449:INFO:delta_performer.cc(450)] PartitionInfo new tz sha256: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg= size: 2060288
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799481:INFO:delta_performer.cc(450)] PartitionInfo new uefisecapp sha256: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo= size: 126976
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799514:INFO:delta_performer.cc(450)] PartitionInfo new xbl sha256: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE= size: 3371008
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799548:INFO:delta_performer.cc(450)] PartitionInfo new xbl_config sha256: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY= size: 90112
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799581:INFO:delta_performer.cc(450)] PartitionInfo new modem sha256: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE= size: 74108928
10-23 13:33:15.799 1031 1031 I update_engine: [1023/133315.799870:INFO:delta_performer.cc(384)] Opening /dev/block/dm-1 partition without O_DSYNC
10-23 13:33:15.801 1031 1031 I update_engine: [1023/133315.801200:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:33:15.801 1031 1031 I update_engine: [1023/133315.801315:INFO:delta_performer.cc(396)] Applying 263 operations to partition "vendor"
10-23 13:33:15.801 1031 1031 I update_engine: [1023/133315.801361:INFO:delta_performer.cc(654)] Starting to apply update payload operations
10-23 13:33:19.925 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.010).
10-23 13:33:19.969 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:24.867 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.020).
10-23 13:33:24.908 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:26.924 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:33:26.933 7088 15996 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:33:29.606 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.030).
10-23 13:33:29.646 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:34.275 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.040).
10-23 13:33:34.309 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:38.419 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.050).
10-23 13:33:38.462 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:43.749 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.060).
10-23 13:33:43.792 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:44.530 1031 1031 I update_engine: [1023/133344.530446:INFO:delta_performer.cc(208)] Completed 163/1826 operations (8%), 97563865/1576938951 bytes downloaded (6%), overall progress 7%
10-23 13:33:47.957 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.070).
10-23 13:33:47.998 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:51.997 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.080).
10-23 13:33:52.052 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:55.301 1031 1031 I update_engine: [1023/133355.301263:INFO:delta_performer.cc(208)] Completed 220/1826 operations (12%), 138933142/1576938951 bytes downloaded (8%), overall progress 10%
10-23 13:33:56.077 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.090).
10-23 13:33:56.113 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:33:59.648 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.100).
10-23 13:33:59.695 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:01.742 1031 1031 I update_engine: [1023/133401.742758:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/boot_b partition without O_DSYNC
10-23 13:34:01.744 1031 1031 I update_engine: [1023/133401.744779:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:34:01.745 1031 1031 I update_engine: [1023/133401.745069:INFO:delta_performer.cc(396)] Applying 32 operations to partition "boot"
10-23 13:34:03.485 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.110).
10-23 13:34:03.529 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:06.940 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.120).
10-23 13:34:07.094 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:07.918 1031 1031 I update_engine: [1023/133407.918241:INFO:delta_performer.cc(384)] Opening /dev/block/dm-4 partition without O_DSYNC
10-23 13:34:07.920 1031 1031 I update_engine: [1023/133407.920505:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:34:07.920 1031 1031 I update_engine: [1023/133407.920786:INFO:delta_performer.cc(396)] Applying 392 operations to partition "system"
10-23 13:34:10.540 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.130).
10-23 13:34:10.577 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:13.855 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.140).
10-23 13:34:13.900 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:17.612 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.150).
10-23 13:34:17.646 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:21.195 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.160).
10-23 13:34:21.232 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:24.483 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.170).
10-23 13:34:24.553 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:25.350 1031 1031 I update_engine: [1023/133425.350477:INFO:delta_performer.cc(208)] Completed 433/1826 operations (23%), 272084901/1576938951 bytes downloaded (17%), overall progress 19%
10-23 13:34:26.124 1031 1031 I update_engine: [1023/133426.124333:INFO:delta_performer.cc(208)] Completed 439/1826 operations (24%), 279342948/1576938951 bytes downloaded (17%), overall progress 20%
10-23 13:34:26.896 7088 7368 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:34:26.900 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.180).
10-23 13:34:26.925 7088 16266 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:34:26.950 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:30.191 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.190).
10-23 13:34:30.226 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:33.016 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.200).
10-23 13:34:33.058 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:36.364 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.210).
10-23 13:34:36.396 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:39.747 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.220).
10-23 13:34:39.786 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:42.569 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.230).
10-23 13:34:42.629 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:46.361 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.240).
10-23 13:34:46.400 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:49.612 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.250).
10-23 13:34:49.653 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:52.982 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.260).
10-23 13:34:53.022 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:56.163 1031 1031 I update_engine: [1023/133456.163113:INFO:delta_performer.cc(208)] Completed 610/1826 operations (33%), 425569048/1576938951 bytes downloaded (26%), overall progress 29%
10-23 13:34:56.182 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.270).
10-23 13:34:56.225 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:34:57.557 1031 1031 I update_engine: [1023/133457.557739:INFO:delta_performer.cc(208)] Completed 621/1826 operations (34%), 432122582/1576938951 bytes downloaded (27%), overall progress 30%
10-23 13:34:58.185 7088 7368 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:34:58.195 7088 16270 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:34:58.204 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:34:58.215 7088 16270 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:34:59.569 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.280).
10-23 13:34:59.604 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:02.958 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.290).
10-23 13:35:02.988 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:06.054 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.300).
10-23 13:35:06.101 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:06.707 1031 1031 I update_engine: [1023/133506.707090:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/vbmeta_b partition without O_DSYNC
10-23 13:35:06.709 1031 1031 I update_engine: [1023/133506.709590:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:35:06.709 1031 1031 I update_engine: [1023/133506.709918:INFO:delta_performer.cc(396)] Applying 1 operations to partition "vbmeta"
10-23 13:35:06.717 1031 1031 I update_engine: [1023/133506.716975:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/dtbo_b partition without O_DSYNC
10-23 13:35:06.718 1031 1031 I update_engine: [1023/133506.718770:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:35:06.719 1031 1031 I update_engine: [1023/133506.719044:INFO:delta_performer.cc(396)] Applying 4 operations to partition "dtbo"
10-23 13:35:06.826 1031 1031 I update_engine: [1023/133506.826583:INFO:delta_performer.cc(384)] Opening /dev/block/dm-9 partition without O_DSYNC
10-23 13:35:06.828 1031 1031 I update_engine: [1023/133506.828675:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:35:06.829 1031 1031 I update_engine: [1023/133506.829013:INFO:delta_performer.cc(396)] Applying 975 operations to partition "product"
10-23 13:35:08.622 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.310).
10-23 13:35:08.657 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:11.536 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.320).
10-23 13:35:11.566 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:15.194 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.330).
10-23 13:35:15.237 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:17.940 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.340).
10-23 13:35:17.981 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:21.459 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.350).
10-23 13:35:21.507 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:24.585 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.360).
10-23 13:35:24.622 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:26.903 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:35:26.915 7088 16270 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:35:27.504 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.370).
10-23 13:35:27.550 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:27.799 1031 1031 I update_engine: [1023/133527.799573:INFO:delta_performer.cc(208)] Completed 801/1826 operations (43%), 585116068/1576938951 bytes downloaded (37%), overall progress 39%
10-23 13:35:28.575 1031 1031 I update_engine: [1023/133528.575535:INFO:delta_performer.cc(208)] Completed 804/1826 operations (44%), 589129268/1576938951 bytes downloaded (37%), overall progress 40%
10-23 13:35:31.047 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.380).
10-23 13:35:31.086 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:34.155 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.390).
10-23 13:35:34.199 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:37.512 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.400).
10-23 13:35:37.548 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:40.448 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.410).
10-23 13:35:40.482 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:43.118 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.420).
10-23 13:35:43.159 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:46.459 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.430).
10-23 13:35:46.506 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:49.742 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.440).
10-23 13:35:49.790 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:52.686 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.450).
10-23 13:35:52.723 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:55.947 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.460).
10-23 13:35:55.990 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:35:58.579 1031 1031 I update_engine: [1023/133558.579285:INFO:delta_performer.cc(208)] Completed 928/1826 operations (50%), 738173381/1576938951 bytes downloaded (46%), overall progress 48%
10-23 13:35:59.013 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.470).
10-23 13:35:59.051 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:02.465 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.480).
10-23 13:36:02.510 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:05.412 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.490).
10-23 13:36:05.455 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:07.178 1031 1031 I update_engine: [1023/133607.178153:INFO:delta_performer.cc(208)] Completed 950/1826 operations (52%), 782196854/1576938951 bytes downloaded (49%), overall progress 50%
10-23 13:36:08.748 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.500).
10-23 13:36:08.788 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:12.348 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.510).
10-23 13:36:12.383 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:16.349 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.520).
10-23 13:36:16.397 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:19.915 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.530).
10-23 13:36:19.990 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:22.430 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.540).
10-23 13:36:22.475 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:25.726 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.550).
10-23 13:36:25.771 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:26.904 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:36:26.915 7088 16270 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:36:28.313 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.560).
10-23 13:36:28.378 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:31.436 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.570).
10-23 13:36:31.471 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:34.833 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.580).
10-23 13:36:34.869 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:37.253 1031 1031 I update_engine: [1023/133637.253322:INFO:delta_performer.cc(208)] Completed 1102/1826 operations (60%), 929046372/1576938951 bytes downloaded (58%), overall progress 59%
10-23 13:36:37.379 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.590).
10-23 13:36:37.417 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:40.698 1031 1031 I update_engine: [1023/133640.698406:INFO:delta_performer.cc(208)] Completed 1110/1826 operations (60%), 946166663/1576938951 bytes downloaded (60%), overall progress 60%
10-23 13:36:40.740 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.600).
10-23 13:36:40.786 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:44.331 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.610).
10-23 13:36:44.385 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:47.554 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.620).
10-23 13:36:47.593 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:51.231 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.630).
10-23 13:36:51.271 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:54.272 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.640).
10-23 13:36:54.320 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:36:57.444 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.650).
10-23 13:36:57.484 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:00.902 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.660).
10-23 13:37:00.934 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:04.026 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.670).
10-23 13:37:04.060 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:07.407 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.680).
10-23 13:37:07.439 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:10.794 1031 1031 I update_engine: [1023/133710.794706:INFO:delta_performer.cc(208)] Completed 1255/1826 operations (68%), 1088280403/1576938951 bytes downloaded (69%), overall progress 68%
10-23 13:37:10.811 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.690).
10-23 13:37:10.849 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:14.226 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.700).
10-23 13:37:14.265 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:14.315 1031 1031 I update_engine: [1023/133714.315328:INFO:delta_performer.cc(208)] Completed 1279/1826 operations (70%), 1104484051/1576938951 bytes downloaded (70%), overall progress 70%
10-23 13:37:17.330 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.710).
10-23 13:37:17.377 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:20.872 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.720).
10-23 13:37:20.910 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:24.251 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.730).
10-23 13:37:24.300 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:26.899 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:37:26.912 7088 16270 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:37:27.193 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.740).
10-23 13:37:27.237 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:30.466 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.750).
10-23 13:37:30.502 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:33.735 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.760).
10-23 13:37:33.774 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:37.115 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.770).
10-23 13:37:37.165 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:39.878 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.780).
10-23 13:37:40.199 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:43.748 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.790).
10-23 13:37:43.784 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:44.335 1031 1031 I update_engine: [1023/133744.335760:INFO:delta_performer.cc(208)] Completed 1444/1826 operations (79%), 1248694918/1576938951 bytes downloaded (79%), overall progress 78%
10-23 13:37:46.675 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.800).
10-23 13:37:46.711 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:49.021 1031 1031 I update_engine: [1023/133749.021570:INFO:delta_performer.cc(208)] Completed 1461/1826 operations (80%), 1275498932/1576938951 bytes downloaded (80%), overall progress 80%
10-23 13:37:49.382 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.810).
10-23 13:37:49.423 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:52.459 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.820).
10-23 13:37:52.507 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:56.304 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.830).
10-23 13:37:56.340 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:37:59.581 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.840).
10-23 13:37:59.622 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:02.917 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:38:02.929 7088 16270 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:38:03.049 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.850).
10-23 13:38:03.098 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:06.789 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.860).
10-23 13:38:06.820 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:09.814 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.870).
10-23 13:38:09.852 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:15.012 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.880).
10-23 13:38:15.046 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:18.466 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.890).
10-23 13:38:18.510 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:19.174 1031 1031 I update_engine: [1023/133819.174771:INFO:delta_performer.cc(208)] Completed 1624/1826 operations (88%), 1407193380/1576938951 bytes downloaded (89%), overall progress 88%
10-23 13:38:22.659 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.900).
10-23 13:38:22.703 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:24.125 1031 1031 I update_engine: [1023/133824.125724:INFO:delta_performer.cc(208)] Completed 1644/1826 operations (90%), 1423936858/1576938951 bytes downloaded (90%), overall progress 90%
10-23 13:38:26.911 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:38:26.922 7088 16526 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:38:27.279 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.910).
10-23 13:38:27.312 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:31.310 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.920).
10-23 13:38:31.356 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:32.849 1031 1031 I update_engine: [1023/133832.849340:INFO:delta_performer.cc(384)] Opening /dev/block/dm-10 partition without O_DSYNC
10-23 13:38:32.851 1031 1031 I update_engine: [1023/133832.851463:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:32.852 1031 1031 I update_engine: [1023/133832.852437:INFO:delta_performer.cc(396)] Applying 111 operations to partition "system_ext"
10-23 13:38:35.798 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.930).
10-23 13:38:35.841 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:39.888 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.941).
10-23 13:38:39.926 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:43.823 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.951).
10-23 13:38:43.869 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:50.397 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.961).
10-23 13:38:50.439 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:53.640 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.971).
10-23 13:38:53.680 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:38:54.160 1031 1031 I update_engine: [1023/133854.160357:INFO:delta_performer.cc(208)] Completed 1776/1826 operations (97%), 1532578331/1576938951 bytes downloaded (97%), overall progress 96%
10-23 13:38:54.772 1031 1031 I update_engine: [1023/133854.772815:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/vbmeta_system_b partition without O_DSYNC
10-23 13:38:54.774 1031 1031 I update_engine: [1023/133854.774637:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.774 1031 1031 I update_engine: [1023/133854.774904:INFO:delta_performer.cc(396)] Applying 1 operations to partition "vbmeta_system"
10-23 13:38:54.779 1031 1031 I update_engine: [1023/133854.779611:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/abl_b partition without O_DSYNC
10-23 13:38:54.781 1031 1031 I update_engine: [1023/133854.781110:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.781 1031 1031 I update_engine: [1023/133854.781289:INFO:delta_performer.cc(396)] Applying 1 operations to partition "abl"
10-23 13:38:54.819 1031 1031 I update_engine: [1023/133854.819842:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/aop_b partition without O_DSYNC
10-23 13:38:54.822 1031 1031 I update_engine: [1023/133854.821961:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.822 1031 1031 I update_engine: [1023/133854.822296:INFO:delta_performer.cc(396)] Applying 1 operations to partition "aop"
10-23 13:38:54.841 1031 1031 I update_engine: [1023/133854.841070:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/devcfg_b partition without O_DSYNC
10-23 13:38:54.842 1031 1031 I update_engine: [1023/133854.842732:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.843 1031 1031 I update_engine: [1023/133854.843006:INFO:delta_performer.cc(396)] Applying 1 operations to partition "devcfg"
10-23 13:38:54.849 1031 1031 I update_engine: [1023/133854.849708:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/hyp_b partition without O_DSYNC
10-23 13:38:54.851 1031 1031 I update_engine: [1023/133854.851436:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.851 1031 1031 I update_engine: [1023/133854.851630:INFO:delta_performer.cc(396)] Applying 1 operations to partition "hyp"
10-23 13:38:54.878 1031 1031 I update_engine: [1023/133854.878813:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/keymaster_b partition without O_DSYNC
10-23 13:38:54.880 1031 1031 I update_engine: [1023/133854.880731:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.881 1031 1031 I update_engine: [1023/133854.881022:INFO:delta_performer.cc(396)] Applying 1 operations to partition "keymaster"
10-23 13:38:54.903 1031 1031 I update_engine: [1023/133854.902937:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/qupfw_b partition without O_DSYNC
10-23 13:38:54.904 1031 1031 I update_engine: [1023/133854.904851:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.905 1031 1031 I update_engine: [1023/133854.905344:INFO:delta_performer.cc(396)] Applying 1 operations to partition "qupfw"
10-23 13:38:54.912 1031 1031 I update_engine: [1023/133854.912449:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/tz_b partition without O_DSYNC
10-23 13:38:54.913 1031 1031 I update_engine: [1023/133854.913889:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:54.914 1031 1031 I update_engine: [1023/133854.914030:INFO:delta_performer.cc(396)] Applying 1 operations to partition "tz"
10-23 13:38:55.030 1031 1031 I update_engine: [1023/133855.030546:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/uefisecapp_b partition without O_DSYNC
10-23 13:38:55.032 1031 1031 I update_engine: [1023/133855.032316:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:55.032 1031 1031 I update_engine: [1023/133855.032643:INFO:delta_performer.cc(396)] Applying 1 operations to partition "uefisecapp"
10-23 13:38:55.049 1031 1031 I update_engine: [1023/133855.049449:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/xbl_b partition without O_DSYNC
10-23 13:38:55.051 1031 1031 I update_engine: [1023/133855.051076:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:55.051 1031 1031 I update_engine: [1023/133855.051407:INFO:delta_performer.cc(396)] Applying 2 operations to partition "xbl"
10-23 13:38:55.504 1031 1031 I update_engine: [1023/133855.504170:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/xbl_config_b partition without O_DSYNC
10-23 13:38:55.509 1031 1031 I update_engine: [1023/133855.509341:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:55.509 1031 1031 I update_engine: [1023/133855.509852:INFO:delta_performer.cc(396)] Applying 1 operations to partition "xbl_config"
10-23 13:38:55.524 1031 1031 I update_engine: [1023/133855.524399:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/modem_b partition without O_DSYNC
10-23 13:38:55.527 1031 1031 I update_engine: [1023/133855.527760:INFO:delta_performer.cc(127)] Caching writes.
10-23 13:38:55.528 1031 1031 I update_engine: [1023/133855.528153:INFO:delta_performer.cc(396)] Applying 36 operations to partition "modem"
10-23 13:38:57.193 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.981).
10-23 13:38:57.230 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:39:00.702 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.991).
10-23 13:39:00.737 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:39:03.387 1031 1031 I update_engine: [1023/133903.387091:INFO:delta_performer.cc(208)] Completed 1826/1826 operations (100%), 1576938951/1576938951 bytes downloaded (100%), overall progress 100%
10-23 13:39:03.391 1031 1031 I update_engine: [1023/133903.391105:INFO:delta_performer.cc(1602)] Extracted signature data of size 523 at 1576833268
10-23 13:39:03.392 1031 1031 I update_engine: [1023/133903.392261:INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
10-23 13:39:03.392 1031 1031 I update_engine: [1023/133903.392404:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
10-23 13:39:03.400 1031 1031 I update_engine: [1023/133903.400248:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 13:39:03.400 1031 1031 I update_engine: [1023/133903.400511:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
10-23 13:39:03.400 1031 1031 I update_engine: [1023/133903.400618:INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
10-23 13:39:03.556 1031 1031 I update_engine: [1023/133903.556101:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:39:03.556 1031 1031 I update_engine: [1023/133903.556389:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:39:03.557 1031 1031 I update_engine: [1023/133903.557350:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:39:03.561 1031 1031 I update_engine: [1023/133903.557425:INFO:delta_performer.cc(1829)] Payload hash matches value in payload.
10-23 13:39:03.561 1031 1031 I update_engine: [1023/133903.561318:INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
10-23 13:39:03.561 1031 1031 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 1826 samples, mean = 126.2
10-23 13:39:03.561 1031 1031 I update_engine: 0 -O (12 = 0.7%)
10-23 13:39:03.561 1031 1031 I update_engine: 10 ----------O (132 = 7.2%) {0.7%}
10-23 13:39:03.561 1031 1031 I update_engine: 18 ----O (55 = 3.0%) {7.9%}
10-23 13:39:03.561 1031 1031 I update_engine: 32 ---O (39 = 2.1%) {10.9%}
10-23 13:39:03.561 1031 1031 I update_engine: 57 ----------------------------O (355 = 19.4%) {13.0%}
10-23 13:39:03.561 1031 1031 I update_engine: 101 ------------------------------------------------------------------------O (927 = 50.8%) {32.5%}
10-23 13:39:03.561 1031 1031 I update_engine: 179 -----------------------O (299 = 16.4%) {83.2%}
10-23 13:39:03.561 1031 1031 I update_engine: 317 -O (7 = 0.4%) {99.6%}
10-23 13:39:03.561 1031 1031 I update_engine: 561 ...
10-23 13:39:03.561 1031 1031 I update_engine:
10-23 13:39:03.561 1031 1031 I update_engine:
10-23 13:39:03.568 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
10-23 13:39:03.569 1031 1031 I update_engine: [1023/133903.569435:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
10-23 13:39:03.569 1031 1031 I update_engine: [1023/133903.569627:INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
10-23 13:39:03.571 1031 1031 I update_engine: [1023/133903.571320:INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (vendor) on device /dev/block/dm-1
10-23 13:39:04.905 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:05.042 1031 1031 I update_engine: [1023/133905.042867:INFO:filesystem_verifier_action.cc(237)] Hash of vendor: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4=
10-23 13:39:05.228 1031 1031 I update_engine: [1023/133905.228378:INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (boot) on device /dev/block/bootdevice/by-name/boot_b
10-23 13:39:05.443 1031 1031 I update_engine: [1023/133905.443109:INFO:filesystem_verifier_action.cc(237)] Hash of boot: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU=
10-23 13:39:05.473 1031 1031 I update_engine: [1023/133905.473352:INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (system) on device /dev/block/dm-4
10-23 13:39:07.614 1031 1031 I update_engine: [1023/133907.614231:INFO:filesystem_verifier_action.cc(237)] Hash of system: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q=
10-23 13:39:07.881 1031 1031 I update_engine: [1023/133907.881639:INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (vbmeta) on device /dev/block/bootdevice/by-name/vbmeta_b
10-23 13:39:07.886 1031 1031 I update_engine: [1023/133907.886342:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg=
10-23 13:39:07.886 1031 1031 I update_engine: [1023/133907.886704:INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (dtbo) on device /dev/block/bootdevice/by-name/dtbo_b
10-23 13:39:07.917 1031 1031 I update_engine: [1023/133907.917828:INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c=
10-23 13:39:07.922 1031 1031 I update_engine: [1023/133907.922276:INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (product) on device /dev/block/dm-9
10-23 13:39:13.570 1031 1031 I update_engine: [1023/133913.570828:INFO:filesystem_verifier_action.cc(237)] Hash of product: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA=
10-23 13:39:14.146 1031 1031 I update_engine: [1023/133914.146775:INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (system_ext) on device /dev/block/dm-10
10-23 13:39:14.829 1031 1031 I update_engine: [1023/133914.829201:INFO:filesystem_verifier_action.cc(237)] Hash of system_ext: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM=
10-23 13:39:14.928 1031 1031 I update_engine: [1023/133914.928048:INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (vbmeta_system) on device /dev/block/bootdevice/by-name/vbmeta_system_b
10-23 13:39:14.931 1031 1031 I update_engine: [1023/133914.931601:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_system: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA=
10-23 13:39:14.931 1031 1031 I update_engine: [1023/133914.931916:INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (abl) on device /dev/block/bootdevice/by-name/abl_b
10-23 13:39:14.937 1031 1031 I update_engine: [1023/133914.937731:INFO:filesystem_verifier_action.cc(237)] Hash of abl: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA=
10-23 13:39:14.938 1031 1031 I update_engine: [1023/133914.938736:INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (aop) on device /dev/block/bootdevice/by-name/aop_b
10-23 13:39:14.941 1031 1031 I update_engine: [1023/133914.941159:INFO:filesystem_verifier_action.cc(237)] Hash of aop: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk=
10-23 13:39:14.941 1031 1031 I update_engine: [1023/133914.941426:INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (devcfg) on device /dev/block/bootdevice/by-name/devcfg_b
10-23 13:39:14.942 1031 1031 I update_engine: [1023/133914.942722:INFO:filesystem_verifier_action.cc(237)] Hash of devcfg: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE=
10-23 13:39:14.942 1031 1031 I update_engine: [1023/133914.942900:INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (hyp) on device /dev/block/bootdevice/by-name/hyp_b
10-23 13:39:14.945 1031 1031 I update_engine: [1023/133914.945535:INFO:filesystem_verifier_action.cc(237)] Hash of hyp: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w=
10-23 13:39:14.946 1031 1031 I update_engine: [1023/133914.945980:INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (keymaster) on device /dev/block/bootdevice/by-name/keymaster_b
10-23 13:39:14.948 1031 1031 I update_engine: [1023/133914.948723:INFO:filesystem_verifier_action.cc(237)] Hash of keymaster: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0=
10-23 13:39:14.949 1031 1031 I update_engine: [1023/133914.949052:INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (qupfw) on device /dev/block/bootdevice/by-name/qupfw_b
10-23 13:39:14.950 1031 1031 I update_engine: [1023/133914.950926:INFO:filesystem_verifier_action.cc(237)] Hash of qupfw: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w=
10-23 13:39:14.951 1031 1031 I update_engine: [1023/133914.951097:INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (tz) on device /dev/block/bootdevice/by-name/tz_b
10-23 13:39:14.961 1031 1031 I update_engine: [1023/133914.960946:INFO:filesystem_verifier_action.cc(237)] Hash of tz: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg=
10-23 13:39:14.962 1031 1031 I update_engine: [1023/133914.962572:INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (uefisecapp) on device /dev/block/bootdevice/by-name/uefisecapp_b
10-23 13:39:14.964 1031 1031 I update_engine: [1023/133914.964572:INFO:filesystem_verifier_action.cc(237)] Hash of uefisecapp: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo=
10-23 13:39:14.964 1031 1031 I update_engine: [1023/133914.964772:INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (xbl) on device /dev/block/bootdevice/by-name/xbl_b
10-23 13:39:14.977 1031 1031 I update_engine: [1023/133914.977833:INFO:filesystem_verifier_action.cc(237)] Hash of xbl: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE=
10-23 13:39:14.979 1031 1031 I update_engine: [1023/133914.979719:INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (xbl_config) on device /dev/block/bootdevice/by-name/xbl_config_b
10-23 13:39:14.981 1031 1031 I update_engine: [1023/133914.981702:INFO:filesystem_verifier_action.cc(237)] Hash of xbl_config: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY=
10-23 13:39:14.982 1031 1031 I update_engine: [1023/133914.982029:INFO:filesystem_verifier_action.cc(117)] Hashing partition 18 (modem) on device /dev/block/bootdevice/by-name/modem_b
10-23 13:39:15.213 1031 1031 I update_engine: [1023/133915.213832:INFO:filesystem_verifier_action.cc(237)] Hash of modem: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE=
10-23 13:39:15.250 1031 1031 I update_engine: [1023/133915.250254:INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
10-23 13:39:15.250 1031 1031 I update_engine: [1023/133915.250531:INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
10-23 13:39:15.252 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
10-23 13:39:15.257 1031 1031 I update_engine: [1023/133915.256920:INFO:postinstall_runner_action.cc(172)] Performing postinst (bin/checkpoint_gc at /postinstall/bin/checkpoint_gc) installed on device /dev/block/dm-1 and mountable device /dev/block/dm-1
10-23 13:39:15.258 1031 1031 I update_engine: [1023/133915.257173:INFO:postinstall_runner_action.cc(179)] Format file for new bin/checkpoint_gc is: data
10-23 13:39:15.309 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:15.341 1031 1031 I update_engine: [1023/133915.341085:INFO:subprocess.cc(157)] Subprocess output:
10-23 13:39:15.341 1031 1031 I update_engine: realpath: /dev/block/dm-8: Permission denied
10-23 13:39:15.341 1031 1031 I update_engine:
10-23 13:39:15.347 1031 1031 I update_engine: [1023/133915.347477:INFO:postinstall_runner_action.cc(172)] Performing postinst (system/bin/otapreopt_script at /postinstall/system/bin/otapreopt_script) installed on device /dev/block/dm-4 and mountable device /dev/block/dm-4
10-23 13:39:15.350 1031 1031 I update_engine: [1023/133915.347754:INFO:postinstall_runner_action.cc(179)] Format file for new system/bin/otapreopt_script is: data
10-23 13:39:15.354 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:20.789 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.510).
10-23 13:39:20.824 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:26.447 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.520).
10-23 13:39:26.489 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:26.910 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:39:26.926 7088 16709 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:39:32.063 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.530).
10-23 13:39:32.100 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:37.706 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.540).
10-23 13:39:37.748 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:44.811 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.550).
10-23 13:39:44.846 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:46.722 7088 16991 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.USER_PRESENT flg=0x24200010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 13:39:46.794 7088 16991 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 11.
10-23 13:39:50.388 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.560).
10-23 13:39:50.461 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:39:56.016 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.570).
10-23 13:39:56.044 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:05.897 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.585).
10-23 13:40:05.927 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:11.677 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.595).
10-23 13:40:11.710 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:17.702 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.605).
10-23 13:40:17.737 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:23.500 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.615).
10-23 13:40:23.529 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:26.897 7088 17593 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:40:26.906 7088 17580 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:40:30.537 7088 8209 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.625).
10-23 13:40:30.567 7088 8209 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:36.248 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.635).
10-23 13:40:36.271 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:42.079 7088 7912 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.645).
10-23 13:40:42.128 7088 7912 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:47.895 7088 8209 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.655).
10-23 13:40:47.923 7088 8209 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:40:55.126 7088 8101 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.665).
10-23 13:40:55.165 7088 8101 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:00.827 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.675).
10-23 13:41:00.882 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:06.914 7088 7159 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.685).
10-23 13:41:06.975 7088 7159 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:14.256 7088 8209 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.695).
10-23 13:41:14.295 7088 8209 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:22.730 7088 7951 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.710).
10-23 13:41:22.759 7088 7951 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:26.905 7088 17592 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:41:26.913 7088 19565 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:41:28.220 7088 7951 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.720).
10-23 13:41:28.253 7088 7951 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:34.388 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.730).
10-23 13:41:34.438 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:41.617 7088 7110 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.740).
10-23 13:41:41.648 7088 7110 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:47.165 7088 7110 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.750).
10-23 13:41:47.198 7088 7110 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:53.030 7088 8209 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.760).
10-23 13:41:53.074 7088 8209 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:41:59.438 7088 7951 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.770).
10-23 13:41:59.473 7088 7951 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:06.872 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.780).
10-23 13:42:06.898 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:08.682 7088 20816 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.gservices.intent.action.GSERVICES_CHANGED flg=0x1000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver }.
10-23 13:42:08.704 7088 20825 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:42:13.351 7088 7951 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.790).
10-23 13:42:13.413 7088 7951 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:18.878 7088 7912 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.800).
10-23 13:42:18.906 7088 7912 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:24.720 7088 7912 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.810).
10-23 13:42:24.779 7088 7912 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:26.896 7088 10601 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:42:26.916 7088 21273 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:42:32.084 7088 7381 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.820).
10-23 13:42:32.117 7088 7381 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:37.834 7088 7381 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.830).
10-23 13:42:37.887 7088 7381 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:43.552 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.840).
10-23 13:42:43.587 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:54.127 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.855).
10-23 13:42:54.165 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:42:59.769 7088 8010 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.865).
10-23 13:42:59.808 7088 8010 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:05.455 7088 7912 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.875).
10-23 13:43:05.489 7088 7912 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:11.336 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.885).
10-23 13:43:11.393 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:18.887 7088 7951 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.895).
10-23 13:43:18.912 7088 7951 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:24.957 7088 7106 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.905).
10-23 13:43:25.002 7088 7106 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:26.907 7088 18706 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:43:26.917 7088 22628 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:43:30.588 7088 7106 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.915).
10-23 13:43:30.674 7088 7106 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:36.236 7088 7912 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.925).
10-23 13:43:36.270 7088 7912 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:43.589 7088 7912 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.935).
10-23 13:43:43.628 7088 7912 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:49.752 7088 10596 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.945).
10-23 13:43:49.800 7088 10596 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:43:55.415 7088 7555 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.955).
10-23 13:43:55.450 7088 7555 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:02.445 7088 7555 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.965).
10-23 13:44:02.484 7088 7555 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:11.002 7088 9333 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.980).
10-23 13:44:11.037 7088 9333 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:17.053 7088 7556 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.990).
10-23 13:44:17.100 7088 7556 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:22.770 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 1.000).
10-23 13:44:22.808 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:24.156 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 1.000).
10-23 13:44:24.192 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:24.210 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:24.239 1031 1031 I update_engine: [1023/134424.239383:INFO:subprocess.cc(157)] Subprocess output:
10-23 13:44:24.239 1031 1031 I update_engine: Complete or error.
10-23 13:44:24.239 1031 1031 I update_engine:
10-23 13:44:24.246 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:44:24.269 1031 1031 I update_engine: [1023/134424.269722:INFO:postinstall_runner_action.cc(364)] All post-install commands succeeded
10-23 13:44:24.270 1031 1031 I update_engine: [1023/134424.270860:INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kSuccess
10-23 13:44:24.270 1031 1031 I update_engine: [1023/134424.270958:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 13:44:24.272 1031 1031 I update_engine: [1023/134424.272284:INFO:update_attempter_android.cc(462)] Update successfully applied, waiting to reboot.
10-23 13:44:24.272 1031 1031 I update_engine: [1023/134424.272405:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, system_ext_b, vendor_b] from device mapper
10-23 13:44:24.502 1031 1031 I update_engine: [1023/134424.501869:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 13:44:24.913 1031 1031 I update_engine: [1023/134424.913263:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 13:44:25.332 1031 1031 I update_engine: [1023/134425.332396:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_ext_b from device mapper.
10-23 13:44:25.797 1031 1031 I update_engine: [1023/134425.797428:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 13:44:25.805 7088 8045 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
10-23 13:44:25.810 1031 1031 I update_engine: [1023/134425.810356:INFO:metrics_reporter_android.cc(29)] uploading 1 to histogram for metric ota_update_engine_attempt_number
10-23 13:44:25.811 1031 1031 I update_engine: [1023/134425.811141:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 13:44:25.811 1031 1031 I update_engine: [1023/134425.811571:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 13:44:25.811 1031 1031 I update_engine: [1023/134425.811939:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 13:44:25.812 1031 1031 I update_engine: [1023/134425.812308:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 13:44:25.812 1031 1031 I update_engine: [1023/134425.812683:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_result
10-23 13:44:25.813 1031 1031 I update_engine: [1023/134425.813020:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_error_code
10-23 13:44:25.814 1031 1031 I update_engine: [1023/134425.814545:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 13:44:25.817 1031 1031 I update_engine: [1023/134425.817701:INFO:metrics_reporter_android.cc(29)] uploading 1 to histogram for metric ota_update_engine_successful_update_attempt_count
10-23 13:44:25.818 1031 1031 I update_engine: [1023/134425.818037:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_payload_type
10-23 13:44:25.818 1031 1031 I update_engine: [1023/134425.818290:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_successful_update_payload_size_mib
10-23 13:44:25.818 1031 1031 I update_engine: [1023/134425.818593:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_successful_update_total_bytes_downloaded_mib
10-23 13:44:25.818 1031 1031 I update_engine: [1023/134425.818830:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_download_overhead_percentage
10-23 13:44:25.819 1031 1031 I update_engine: [1023/134425.819467:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_successful_update_fixed_total_duration_in_minutes
10-23 13:44:25.819 1031 1031 I update_engine: [1023/134425.819724:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_reboot_count
10-23 13:44:25.821 1031 1031 I update_engine: [1023/134425.821147:INFO:metrics_utils.cc(366)] Updated Marker = 1/1/1970 0:16:21 GMT
10-23 13:44:25.857 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x006.
10-23 13:44:25.886 7088 8045 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x006.
10-23 13:44:25.918 7088 16001 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 674.42 seconds.
10-23 13:44:25.960 7088 16001 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:44:26.185 7088 16001 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.26 seconds.
10-23 13:44:38.534 7088 24216 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.USER_PRESENT flg=0x24200010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 13:44:38.580 7088 24216 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 11.
10-23 13:44:38.583 7088 24216 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 13:44:38.592 7088 24216 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:44:38.643 7088 24216 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.06 seconds.
10-23 13:45:06.962 7088 24658 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.USER_PRESENT flg=0x24200010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 13:45:06.986 7088 24675 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 11.
10-23 13:45:06.992 7088 24669 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 13:45:07.027 7088 24669 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:45:07.088 7088 24669 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.08 seconds.
10-23 13:45:41.480 25001 25139 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.android.gms.update.BASE_MODULE_INIT cat=[targeted_intent_op_prefix:.update.control.ReceiverIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 13:45:41.631 25001 25139 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: LogStatus.
10-23 13:45:41.806 25001 25139 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 1.
10-23 13:45:42.040 25001 25139 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 13:45:42.097 25001 25139 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:45:42.680 25001 25139 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.64 seconds.
10-23 13:45:48.075 25001 25143 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.gservices.intent.action.GSERVICES_CHANGED flg=0x1000010 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver }.
10-23 13:45:48.101 25001 25070 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:45:48.107 25001 25260 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 13:45:48.216 25001 25260 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:45:48.399 25001 25260 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.29 seconds.
10-23 13:46:45.107 25001 26560 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=com.google.android.gms.phenotype.com.google.android.gms.update.COMMITTED flg=0x10 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.GmsIntentOperationService (has extras) }.
10-23 13:46:45.125 25001 26562 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:46:45.135 25001 26554 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 13:46:45.170 25001 26554 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:46:45.317 25001 26554 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.18 seconds.
10-23 13:47:35.777 25001 27624 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:47:35.783 25001 27625 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 13:47:35.848 25001 27625 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:47:35.859 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x210, isSetupWizard=false.
10-23 13:47:35.897 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:47:36.052 25001 27625 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 13:47:36.052 25001 27625 I SystemUpdate: [Execution,UpdateEngineDelegate] resetStatus()
10-23 13:47:36.054 1031 1031 I update_engine: [1023/134736.054111:INFO:update_attempter_android.cc(313)] Attempting to reset state from UPDATE_STATUS_UPDATED_NEED_REBOOT to UpdateStatus::IDLE
10-23 13:47:36.130 1031 1031 I update_engine: [1023/134736.130158:INFO:update_attempter_android.cc(343)] Reset status successful
10-23 13:47:36.143 25001 27625 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.35 seconds.
10-23 13:48:03.530 25001 27765 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:48:03.540 25001 27736 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 13:48:03.571 25001 27736 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:48:03.683 25001 27736 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.14 seconds.
10-23 13:51:13.223 25001 28626 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:51:13.230 25001 28627 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 13:51:13.258 25001 28627 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:51:13.261 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:51:13.272 25001 28626 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:51:13.399 25001 28627 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.17 seconds.
10-23 13:51:13.406 25001 28626 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 13:51:13.432 25001 28626 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.03 seconds.
10-23 13:51:13.517 25001 28626 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:51:13.526 25001 28627 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 13:51:13.578 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:51:13.585 25001 28627 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.05 seconds.
10-23 13:51:22.682 25001 28639 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:51:22.690 25001 28639 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 13:51:22.705 25001 28639 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:51:22.816 25001 28639 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.13 seconds.
10-23 13:51:57.897 25001 28688 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:51:57.904 25001 28689 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 13:51:57.936 25001 28689 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:51:57.968 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x210, isSetupWizard=false.
10-23 13:51:58.116 25001 28689 I SystemUpdate: [Execution,SynchronizedUpdateEngine] start(ayce{url=file:///data/ota_package/payload_metadata.bin, payload_binary_file.offset=0, payload_binary_file.length=1576938951, properties=[FILE_HASH=yNdMzP4nHXPaSqS1D97Atji7R6JYwNRdRCyYebkY9+s=, FILE_SIZE=1576938951, METADATA_HASH=s4Rs4XtVL4Bzt4PV1NVNJoZWvqwa63LQMnGYpc4WPi0=, METADATA_SIZE=104637]}, additionalProperties.size=3)
10-23 13:51:58.116 25001 28689 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 13:51:58.131 1031 1031 I update_engine: [1023/135158.131390:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 13:51:58.131 1031 1031 I update_engine: [1023/135158.131688:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: file:///data/ota_package/payload_metadata.bin, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: true, run_post_install: false, is_rollback: false, write_verity: false
10-23 13:51:58.132 1031 1031 I update_engine: [1023/135158.132298:INFO:metrics_utils.cc(357)] Payload Attempt Number = 1
10-23 13:51:58.132 1031 1031 I update_engine: [1023/135158.132542:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:23:53 GMT
10-23 13:51:58.132 1031 1031 I update_engine: [1023/135158.132733:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 0:23:53 GMT
10-23 13:51:58.132 1031 1031 I update_engine: [1023/135158.132788:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133007:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133058:INFO:update_boot_flags_action.cc(36)] Already updated boot flags. Skipping.
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133128:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133165:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133205:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133238:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133282:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: file:///data/ota_package/payload_metadata.bin, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: true, run_post_install: false, is_rollback: false, write_verity: false
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133372:INFO:prefs.cc(122)] update-state-payload-index not present in /data/misc/update_engine/prefs
10-23 13:51:58.133 1031 1031 I update_engine: [1023/135158.133409:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 13:51:58.166 1031 1031 I update_engine: [1023/135158.166399:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 13:51:58.166 1031 1031 I update_engine: [1023/135158.166536:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 0+105160
10-23 13:51:58.169 1031 1031 I update_engine: [1023/135158.169365:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 13:51:58.171 1031 1031 I update_engine: [1023/135158.171093:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 13:51:58.171 1031 1031 I update_engine: [1023/135158.171381:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:51:58.171 1031 1031 I update_engine: [1023/135158.171596:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:51:58.173 1031 1031 I update_engine: [1023/135158.173863:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:51:58.173 1031 1031 I update_engine: [1023/135158.173965:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 13:51:58.181 1031 1031 I update_engine: [1023/135158.181301:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 13:51:58.203 1031 1031 I update_engine: [1023/135158.203520:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 13:51:58.198 1031 1031 W update_engine: type=1400 audit(0.0:620): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.208 1031 1031 I update_engine: [1023/135158.208279:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.267 1031 1031 I update_engine: [1023/135158.267245: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 13:51:58.265 1031 1031 W update_engine: type=1400 audit(0.0:621): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.269 1031 1031 I update_engine: [1023/135158.269228:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.269 1031 1031 I update_engine: [1023/135158.269546:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 13:51:58.270 1031 1031 I update_engine: [1023/135158.270052:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.271 1031 1031 I update_engine: [1023/135158.271495:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.265 1031 1031 W update_engine: type=1400 audit(0.0:622): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.328 1031 1031 I update_engine: [1023/135158.328763:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-4
10-23 13:51:58.325 1031 1031 W update_engine: type=1400 audit(0.0:623): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.330 1031 1031 I update_engine: [1023/135158.330823:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.331 1031 1031 I update_engine: [1023/135158.331188:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 13:51:58.331 1031 1031 I update_engine: [1023/135158.331788:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.328 1031 1031 W update_engine: type=1400 audit(0.0:624): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.333 1031 1031 I update_engine: [1023/135158.333436:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.333 1031 1031 I update_engine: [1023/135158.333816:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 13:51:58.334 1031 1031 I update_engine: [1023/135158.334301:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.328 1031 1031 W update_engine: type=1400 audit(0.0:625): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.335 1031 1031 I update_engine: [1023/135158.335913:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.393 1031 1031 I update_engine: [1023/135158.393403:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-9
10-23 13:51:58.388 1031 1031 W update_engine: type=1400 audit(0.0:626): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.395 1031 1031 I update_engine: [1023/135158.395648:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.453 1031 1031 I update_engine: [1023/135158.453134:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_ext_b to device mapper (force_writable = 1); device path at /dev/block/dm-10
10-23 13:51:58.451 1031 1031 W update_engine: type=1400 audit(0.0:627): avc: denied { search } for name="gsi" dev="sda11" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=0
10-23 13:51:58.455 1031 1031 I update_engine: [1023/135158.455204:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.455 1031 1031 I update_engine: [1023/135158.455601:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 13:51:58.456 1031 1031 I update_engine: [1023/135158.456074:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.457 1031 1031 I update_engine: [1023/135158.457759:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.458 1031 1031 I update_engine: [1023/135158.458229:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 13:51:58.460 1031 1031 I update_engine: [1023/135158.460332:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.463 1031 1031 I update_engine: [1023/135158.462968:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.463 1031 1031 I update_engine: [1023/135158.463353:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 13:51:58.463 1031 1031 I update_engine: [1023/135158.463953:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.465 1031 1031 I update_engine: [1023/135158.465696:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.466 1031 1031 I update_engine: [1023/135158.466117:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 13:51:58.466 1031 1031 I update_engine: [1023/135158.466581:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.467 1031 1031 I update_engine: [1023/135158.467885:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.468 1031 1031 I update_engine: [1023/135158.468158:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 13:51:58.468 1031 1031 I update_engine: [1023/135158.468597:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.469 1031 1031 I update_engine: [1023/135158.469856:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.470 1031 1031 I update_engine: [1023/135158.470140:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 13:51:58.470 1031 1031 I update_engine: [1023/135158.470523:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.471 1031 1031 I update_engine: [1023/135158.471793:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.472 1031 1031 I update_engine: [1023/135158.472063:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 13:51:58.472 1031 1031 I update_engine: [1023/135158.472457:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.473 1031 1031 I update_engine: [1023/135158.473589:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.476 1031 1031 I update_engine: [1023/135158.476863:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 13:51:58.477 1031 1031 I update_engine: [1023/135158.477521:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.479 1031 1031 I update_engine: [1023/135158.479264:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.479 1031 1031 I update_engine: [1023/135158.479777:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 13:51:58.480 1031 1031 I update_engine: [1023/135158.480370:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.487 1031 1031 I update_engine: [1023/135158.487367:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.487 1031 1031 I update_engine: [1023/135158.487883:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 13:51:58.488 1031 1031 I update_engine: [1023/135158.488536:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.490 1031 1031 I update_engine: [1023/135158.490256:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.490 1031 1031 I update_engine: [1023/135158.490640:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 13:51:58.491 1031 1031 I update_engine: [1023/135158.491193:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.493 1031 1031 I update_engine: [1023/135158.493048:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:51:58.493 1031 1031 I update_engine: [1023/135158.493588:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494512:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494661:INFO:delta_performer.cc(450)] PartitionInfo new vendor sha256: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4= size: 550678528
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494705:INFO:delta_performer.cc(450)] PartitionInfo new boot sha256: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU= size: 67108864
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494740:INFO:delta_performer.cc(450)] PartitionInfo new system sha256: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q= size: 820408320
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494774:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta sha256: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg= size: 8192
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494807:INFO:delta_performer.cc(450)] PartitionInfo new dtbo sha256: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c= size: 8388608
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494840:INFO:delta_performer.cc(450)] PartitionInfo new product sha256: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA= size: 2044289024
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494877:INFO:delta_performer.cc(450)] PartitionInfo new system_ext sha256: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM= size: 231182336
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494911:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta_system sha256: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA= size: 4096
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494944:INFO:delta_performer.cc(450)] PartitionInfo new abl sha256: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA= size: 1048576
10-23 13:51:58.494 1031 1031 I update_engine: [1023/135158.494977:INFO:delta_performer.cc(450)] PartitionInfo new aop sha256: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk= size: 167936
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495010:INFO:delta_performer.cc(450)] PartitionInfo new devcfg sha256: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE= size: 45056
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495044:INFO:delta_performer.cc(450)] PartitionInfo new hyp sha256: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w= size: 397312
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495123:INFO:delta_performer.cc(450)] PartitionInfo new keymaster sha256: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0= size: 241664
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495157:INFO:delta_performer.cc(450)] PartitionInfo new qupfw sha256: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w= size: 53248
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495190:INFO:delta_performer.cc(450)] PartitionInfo new tz sha256: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg= size: 2060288
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495224:INFO:delta_performer.cc(450)] PartitionInfo new uefisecapp sha256: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo= size: 126976
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495257:INFO:delta_performer.cc(450)] PartitionInfo new xbl sha256: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE= size: 3371008
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495289:INFO:delta_performer.cc(450)] PartitionInfo new xbl_config sha256: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY= size: 90112
10-23 13:51:58.495 1031 1031 I update_engine: [1023/135158.495323:INFO:delta_performer.cc(450)] PartitionInfo new modem sha256: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE= size: 74108928
10-23 13:51:58.497 1031 1031 I update_engine: [1023/135158.497753:INFO:delta_performer.cc(208)] Resuming after 1826/1826 operations (100%), 1576938951/1576938951 bytes downloaded (100%), overall progress 100%
10-23 13:51:58.497 1031 1031 I update_engine: [1023/135158.497852:INFO:delta_performer.cc(654)] Starting to apply update payload operations
10-23 13:51:58.497 1031 1031 I update_engine: [1023/135158.497886:INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
10-23 13:51:58.497 1031 1031 I update_engine: [1023/135158.497934:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 13:51:58.497 1031 1031 I update_engine: [1023/135158.497966:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 200
10-23 13:51:58.498 1031 1031 I update_engine: [1023/135158.497996:INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
10-23 13:51:58.498 1031 1031 I update_engine: [1023/135158.498080:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:51:58.498 1031 1031 I update_engine: [1023/135158.498166:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:51:58.499 1031 1031 I update_engine: [1023/135158.499282:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:51:58.499 1031 1031 I update_engine: [1023/135158.499467:INFO:delta_performer.cc(1829)] Payload hash matches value in payload.
10-23 13:51:58.499 1031 1031 I update_engine: [1023/135158.499669:INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
10-23 13:51:58.499 1031 1031 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 1826 samples, mean = 126.2
10-23 13:51:58.499 1031 1031 I update_engine: 0 -O (12 = 0.7%)
10-23 13:51:58.499 1031 1031 I update_engine: 10 ----------O (132 = 7.2%) {0.7%}
10-23 13:51:58.499 1031 1031 I update_engine: 18 ----O (55 = 3.0%) {7.9%}
10-23 13:51:58.499 1031 1031 I update_engine: 32 ---O (39 = 2.1%) {10.9%}
10-23 13:51:58.499 1031 1031 I update_engine: 57 ----------------------------O (355 = 19.4%) {13.0%}
10-23 13:51:58.499 1031 1031 I update_engine: 101 ------------------------------------------------------------------------O (927 = 50.8%) {32.5%}
10-23 13:51:58.499 1031 1031 I update_engine: 179 -----------------------O (299 = 16.4%) {83.2%}
10-23 13:51:58.499 1031 1031 I update_engine: 317 -O (7 = 0.4%) {99.6%}
10-23 13:51:58.499 1031 1031 I update_engine: 561 ...
10-23 13:51:58.499 1031 1031 I update_engine:
10-23 13:51:58.499 1031 1031 I update_engine:
10-23 13:51:58.506 1031 1031 I update_engine: [1023/135158.506505:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
10-23 13:51:58.506 1031 1031 I update_engine: [1023/135158.506705:INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
10-23 13:51:58.506 1031 1031 I update_engine: [1023/135158.506777:INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (vendor) on device /dev/block/dm-1
10-23 13:52:00.031 1031 1031 I update_engine: [1023/135200.031619:INFO:filesystem_verifier_action.cc(237)] Hash of vendor: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4=
10-23 13:52:00.215 1031 1031 I update_engine: [1023/135200.215450:INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (boot) on device /dev/block/bootdevice/by-name/boot_b
10-23 13:52:00.420 1031 1031 I update_engine: [1023/135200.420671:INFO:filesystem_verifier_action.cc(237)] Hash of boot: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU=
10-23 13:52:00.449 1031 1031 I update_engine: [1023/135200.449362:INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (system) on device /dev/block/dm-4
10-23 13:52:02.674 1031 1031 I update_engine: [1023/135202.673947:INFO:filesystem_verifier_action.cc(237)] Hash of system: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q=
10-23 13:52:02.952 1031 1031 I update_engine: [1023/135202.952710:INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (vbmeta) on device /dev/block/bootdevice/by-name/vbmeta_b
10-23 13:52:02.956 1031 1031 I update_engine: [1023/135202.956561:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg=
10-23 13:52:02.956 1031 1031 I update_engine: [1023/135202.956841:INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (dtbo) on device /dev/block/bootdevice/by-name/dtbo_b
10-23 13:52:02.985 1031 1031 I update_engine: [1023/135202.985757:INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c=
10-23 13:52:02.989 1031 1031 I update_engine: [1023/135202.989931:INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (product) on device /dev/block/dm-9
10-23 13:52:08.844 1031 1031 I update_engine: [1023/135208.844693:INFO:filesystem_verifier_action.cc(237)] Hash of product: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA=
10-23 13:52:09.333 1031 1031 I update_engine: [1023/135209.333285:INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (system_ext) on device /dev/block/dm-10
10-23 13:52:10.014 1031 1031 I update_engine: [1023/135210.014140:INFO:filesystem_verifier_action.cc(237)] Hash of system_ext: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM=
10-23 13:52:10.098 1031 1031 I update_engine: [1023/135210.098269:INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (vbmeta_system) on device /dev/block/bootdevice/by-name/vbmeta_system_b
10-23 13:52:10.101 1031 1031 I update_engine: [1023/135210.101822:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_system: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA=
10-23 13:52:10.102 1031 1031 I update_engine: [1023/135210.102180:INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (abl) on device /dev/block/bootdevice/by-name/abl_b
10-23 13:52:10.107 1031 1031 I update_engine: [1023/135210.107421:INFO:filesystem_verifier_action.cc(237)] Hash of abl: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA=
10-23 13:52:10.108 1031 1031 I update_engine: [1023/135210.108205:INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (aop) on device /dev/block/bootdevice/by-name/aop_b
10-23 13:52:10.110 1031 1031 I update_engine: [1023/135210.110631:INFO:filesystem_verifier_action.cc(237)] Hash of aop: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk=
10-23 13:52:10.111 1031 1031 I update_engine: [1023/135210.110994:INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (devcfg) on device /dev/block/bootdevice/by-name/devcfg_b
10-23 13:52:10.112 1031 1031 I update_engine: [1023/135210.112431:INFO:filesystem_verifier_action.cc(237)] Hash of devcfg: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE=
10-23 13:52:10.112 1031 1031 I update_engine: [1023/135210.112688:INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (hyp) on device /dev/block/bootdevice/by-name/hyp_b
10-23 13:52:10.116 1031 1031 I update_engine: [1023/135210.115925:INFO:filesystem_verifier_action.cc(237)] Hash of hyp: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w=
10-23 13:52:10.116 1031 1031 I update_engine: [1023/135210.116346:INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (keymaster) on device /dev/block/bootdevice/by-name/keymaster_b
10-23 13:52:10.118 1031 1031 I update_engine: [1023/135210.118250:INFO:filesystem_verifier_action.cc(237)] Hash of keymaster: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0=
10-23 13:52:10.118 1031 1031 I update_engine: [1023/135210.118594:INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (qupfw) on device /dev/block/bootdevice/by-name/qupfw_b
10-23 13:52:10.119 1031 1031 I update_engine: [1023/135210.119732:INFO:filesystem_verifier_action.cc(237)] Hash of qupfw: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w=
10-23 13:52:10.119 1031 1031 I update_engine: [1023/135210.119893:INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (tz) on device /dev/block/bootdevice/by-name/tz_b
10-23 13:52:10.127 1031 1031 I update_engine: [1023/135210.127411:INFO:filesystem_verifier_action.cc(237)] Hash of tz: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg=
10-23 13:52:10.128 1031 1031 I update_engine: [1023/135210.128474:INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (uefisecapp) on device /dev/block/bootdevice/by-name/uefisecapp_b
10-23 13:52:10.130 1031 1031 I update_engine: [1023/135210.130374:INFO:filesystem_verifier_action.cc(237)] Hash of uefisecapp: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo=
10-23 13:52:10.130 1031 1031 I update_engine: [1023/135210.130594:INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (xbl) on device /dev/block/bootdevice/by-name/xbl_b
10-23 13:52:10.142 1031 1031 I update_engine: [1023/135210.142539:INFO:filesystem_verifier_action.cc(237)] Hash of xbl: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE=
10-23 13:52:10.144 1031 1031 I update_engine: [1023/135210.144034:INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (xbl_config) on device /dev/block/bootdevice/by-name/xbl_config_b
10-23 13:52:10.145 1031 1031 I update_engine: [1023/135210.145898:INFO:filesystem_verifier_action.cc(237)] Hash of xbl_config: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY=
10-23 13:52:10.146 1031 1031 I update_engine: [1023/135210.146165:INFO:filesystem_verifier_action.cc(117)] Hashing partition 18 (modem) on device /dev/block/bootdevice/by-name/modem_b
10-23 13:52:10.374 1031 1031 I update_engine: [1023/135210.374816:INFO:filesystem_verifier_action.cc(237)] Hash of modem: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE=
10-23 13:52:10.405 1031 1031 I update_engine: [1023/135210.405541:INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
10-23 13:52:10.405 1031 1031 I update_engine: [1023/135210.405720:INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
10-23 13:52:10.405 1031 1031 I update_engine: [1023/135210.405857:INFO:postinstall_runner_action.cc(87)] Skipping post-install according to install plan.
10-23 13:52:10.422 1031 1031 I update_engine: [1023/135210.422097:INFO:postinstall_runner_action.cc(364)] All post-install commands succeeded
10-23 13:52:10.423 1031 1031 I update_engine: [1023/135210.423078:INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kSuccess
10-23 13:52:10.423 1031 1031 I update_engine: [1023/135210.423177:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 13:52:10.424 1031 1031 I update_engine: [1023/135210.424596:INFO:update_attempter_android.cc(462)] Update successfully applied, waiting to reboot.
10-23 13:52:10.424 1031 1031 I update_engine: [1023/135210.424671:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, system_ext_b, vendor_b] from device mapper
10-23 13:52:10.651 1031 1031 I update_engine: [1023/135210.651390:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 13:52:11.062 1031 1031 I update_engine: [1023/135211.062185:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 13:52:11.526 1031 1031 I update_engine: [1023/135211.526344:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_ext_b from device mapper.
10-23 13:52:11.946 1031 1031 I update_engine: [1023/135211.946678:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 13:52:11.954 1031 1031 I update_engine: [1023/135211.954561:INFO:metrics_reporter_android.cc(29)] uploading 1 to histogram for metric ota_update_engine_attempt_number
10-23 13:52:11.955 1031 1031 I update_engine: [1023/135211.954968:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 13:52:11.955 1031 1031 I update_engine: [1023/135211.955285:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 13:52:11.955 1031 1031 I update_engine: [1023/135211.955596:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 13:52:11.955 1031 1031 I update_engine: [1023/135211.955837:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 13:52:11.956 1031 1031 I update_engine: [1023/135211.956193:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_result
10-23 13:52:11.956 1031 1031 I update_engine: [1023/135211.956426:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_error_code
10-23 13:52:11.958 1031 1031 I update_engine: [1023/135211.958224:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 13:52:11.959 25001 28689 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 14.05 seconds.
10-23 13:52:11.960 1031 1031 I update_engine: [1023/135211.960230:INFO:metrics_reporter_android.cc(29)] uploading 1 to histogram for metric ota_update_engine_successful_update_attempt_count
10-23 13:52:11.960 1031 1031 I update_engine: [1023/135211.960507:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_payload_type
10-23 13:52:11.960 1031 1031 I update_engine: [1023/135211.960725:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_successful_update_payload_size_mib
10-23 13:52:11.960 1031 1031 I update_engine: [1023/135211.960943:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_total_bytes_downloaded_mib
10-23 13:52:11.961 1031 1031 I update_engine: [1023/135211.961162:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_download_overhead_percentage
10-23 13:52:11.961 1031 1031 I update_engine: [1023/135211.961381:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_fixed_total_duration_in_minutes
10-23 13:52:11.961 1031 1031 I update_engine: [1023/135211.961611:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_reboot_count
10-23 13:52:11.962 1031 1031 I update_engine: [1023/135211.962868:INFO:metrics_utils.cc(366)] Updated Marker = 1/1/1970 0:24:07 GMT
10-23 13:52:25.277 25001 28714 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:52:25.281 25001 28715 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 13:52:25.306 25001 28715 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x210, -1.000).
10-23 13:52:25.431 25001 28715 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.14 seconds.
10-23 13:53:51.129 25001 28826 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:53:51.143 25001 28827 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 13:53:51.190 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x210, isSetupWizard=false.
10-23 13:53:51.193 25001 28827 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:53:51.229 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:53:51.349 25001 28827 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 13:53:51.349 25001 28827 I SystemUpdate: [Execution,UpdateEngineDelegate] resetStatus()
10-23 13:53:51.349 1031 1031 I update_engine: [1023/135351.349912:INFO:update_attempter_android.cc(313)] Attempting to reset state from UPDATE_STATUS_UPDATED_NEED_REBOOT to UpdateStatus::IDLE
10-23 13:53:51.422 1031 1031 I update_engine: [1023/135351.422001:INFO:update_attempter_android.cc(343)] Reset status successful
10-23 13:53:51.433 25001 28827 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.28 seconds.
10-23 13:54:07.130 25001 28846 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:54:07.142 25001 28846 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 13:54:07.164 25001 28846 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:54:07.275 25001 28846 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.13 seconds.
10-23 13:54:59.033 25001 28915 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:54:59.043 25001 28916 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 13:54:59.081 25001 28916 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:54:59.083 26171 26171 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:54:59.200 25001 28916 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.16 seconds.
10-23 13:55:54.886 25001 28953 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:55:54.893 25001 28954 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 13:55:54.911 25001 28954 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:55:55.012 25001 28954 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.12 seconds.
10-23 14:00:23.516 1031 1031 I update_engine: [1023/140023.516517:INFO:main.cc(198)] A/B Update Engine terminating with exit code 0
10-23 14:00:23.516 1031 1031 I update_engine: [1023/140023.516927: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