Skip to content

Instantly share code, notes, and snippets.

@nooriro
Created October 23, 2020 08:10
Show Gist options
  • Save nooriro/c60a858134841e529f31b24f5a5a14fb to your computer and use it in GitHub Desktop.
Save nooriro/c60a858134841e529f31b24f5a5a14fb to your computer and use it in GitHub Desktop.
sunfish update log (SUCCEEDED LOG) / from QD4A.200805.003 to RP1A.201005.006 (using FULL OTA IMAGE) / Update URL (Full OTA Image): https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip
--------- beginning of system
--------- beginning of main
10-23 12:41:58.177 3342 5418 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 12:41:58.520 3342 5418 I SystemUpdate: [Control,InstallationControl] Update URL changed from "" to "https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip".
10-23 12:41:58.663 3342 6201 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 1.
10-23 12:41:58.683 3342 6201 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 102.
10-23 12:41:58.685 3342 6201 I SystemUpdate: [Execution,ExecutionManager] Finishing the current execution.
10-23 12:41:58.728 3342 6201 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 12:41:58.802 3342 3868 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 12:41:58.835 3342 3868 I SystemUpdate: [Execution,ExecutionManager] Action pending executed for 0.02 seconds.
10-23 12:41:58.900 3342 3868 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
10-23 12:42:04.162 3342 3868 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_header.bin with size 24.
10-23 12:42:08.008 3342 6201 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 12:42:08.026 3342 7363 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:42:34.790 972 972 I update_engine: [1023/124234.790402:INFO:main.cc(198)] A/B Update Engine terminating with exit code 0
10-23 12:42:34.790 972 972 I update_engine: [1023/124234.790735:INFO:dynamic_partition_control_android.cc(151)] Destroying [] from device mapper
--------- beginning of system
--------- beginning of main
10-23 12:43:27.816 1058 1058 I update_engine: [1023/124327.815872:INFO:main.cc(181)] A/B Update Engine starting
10-23 12:43:27.819 1058 1058 I update_engine: [1023/124327.819691:INFO:boot_control_android.cc(76)] Loaded boot control hidl hal.
10-23 12:43:54.239 3183 5853 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 12:43:54.448 3183 5853 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: LogStatus.
10-23 12:43:54.491 3183 5852 I SystemUpdate: [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.USER_PRESENT flg=0x24200010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService }.
10-23 12:43:54.866 3183 5852 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 1.
10-23 12:43:54.886 3183 5853 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 12:43:54.886 3183 5852 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 11.
10-23 12:43:54.913 3183 5853 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
10-23 12:44:04.053 3183 5853 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_header.bin with size 24.
10-23 12:45:01.148 3183 7913 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 12:45:01.163 3183 7917 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 6.
10-23 12:45:01.304 3183 7917 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 12:45:01.323 3183 7914 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 6.
10-23 12:45:51.906 3183 5853 E SystemUpdate: [Execution,PreDownloadValidateAction] Failed to download.
10-23 12:45:51.906 3183 5853 E SystemUpdate: awuw: Unknown download exception.
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awuv.d(:com.google.android.gms@17786048@17.7.86 (120408-256199907):68)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awuv.a(:com.google.android.gms@17786048@17.7.86 (120408-256199907):6)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awtr.c(:com.google.android.gms@17786048@17.7.86 (120408-256199907):108)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.google.android.gms.update.execution.InstallationIntentOperation.onHandleIntent(:com.google.android.gms@17786048@17.7.86 (120408-256199907):14)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@17786048@17.7.86 (120408-256199907):2)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at qgd.onHandleIntent(:com.google.android.gms@17786048@17.7.86 (120408-256199907):4)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at eeh.run(:com.google.android.gms@17786048@17.7.86 (120408-256199907):10)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at eec.run(:com.google.android.gms@17786048@17.7.86 (120408-256199907):9)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.lang.Thread.run(Thread.java:919)
10-23 12:45:51.906 3183 5853 E SystemUpdate: Caused by: java.util.concurrent.ExecutionException: java.lang.RuntimeException: Execution exception
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.FutureTask.report(FutureTask.java:123)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.FutureTask.get(FutureTask.java:207)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awuv.d(:com.google.android.gms@17786048@17.7.86 (120408-256199907):16)
10-23 12:45:51.906 3183 5853 E SystemUpdate: ... 10 more
10-23 12:45:51.906 3183 5853 E SystemUpdate: Caused by: java.lang.RuntimeException: Execution exception
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awva.run(:com.google.android.gms@17786048@17.7.86 (120408-256199907):4)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:462)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.FutureTask.run(FutureTask.java:266)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at sko.b(:com.google.android.gms@17786048@17.7.86 (120408-256199907):37)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at sko.run(:com.google.android.gms@17786048@17.7.86 (120408-256199907):21)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at sqm.run(Unknown Source:7)
10-23 12:45:51.906 3183 5853 E SystemUpdate: ... 1 more
10-23 12:45:51.906 3183 5853 E SystemUpdate: Caused by: awuw: Unable to get response code.
10-23 12:45:51.906 3183 5853 E SystemUpdate: ... 9 more
10-23 12:45:51.906 3183 5853 E SystemUpdate: Caused by: javax.net.ssl.SSLException: Read error: ssl=0x74f52ec3c8: I/O error during system call, Software caused connection abort
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.google.android.gms.org.conscrypt.NativeCrypto.SSL_read(Native Method)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.google.android.gms.org.conscrypt.NativeSsl.read(:com.google.android.gms@17786048@17.7.86 (120408-256199907):4)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket$SSLInputStream.read(:com.google.android.gms@17786048@17.7.86 (120408-256199907):7)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.okio.Okio$2.read(Okio.java:138)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.okio.AsyncTimeout$2.read(AsyncTimeout.java:213)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.okio.RealBufferedSource.indexOf(RealBufferedSource.java:307)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.okio.RealBufferedSource.indexOf(RealBufferedSource.java:301)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.okio.RealBufferedSource.readUtf8LineStrict(RealBufferedSource.java:197)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.http.Http1xStream.readResponse(Http1xStream.java:188)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.http.Http1xStream.readResponseHeaders(Http1xStream.java:129)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.http.HttpEngine.readNetworkResponse(HttpEngine.java:750)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.http.HttpEngine.readResponse(HttpEngine.java:622)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.huc.HttpURLConnectionImpl.execute(HttpURLConnectionImpl.java:475)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.huc.HttpURLConnectionImpl.getResponse(HttpURLConnectionImpl.java:411)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.huc.HttpURLConnectionImpl.getResponseCode(HttpURLConnectionImpl.java:542)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.huc.DelegatingHttpsURLConnection.getResponseCode(DelegatingHttpsURLConnection.java:106)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.android.okhttp.internal.huc.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:30)
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awva.run(:com.google.android.gms@17786048@17.7.86 (120408-256199907):1)
10-23 12:45:51.906 3183 5853 E SystemUpdate: ... 8 more
10-23 12:45:51.911 3183 5853 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x116, -1.000).
10-23 12:45:52.102 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action pre-download-validate executed for 117.21 seconds.
10-23 12:45:52.112 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action exponential-backoff-delay-execution executed for 0.01 seconds.
10-23 12:45:52.123 3183 5853 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 12:45:52.135 3183 5853 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
10-23 12:45:53.382 3183 5853 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_header.bin with size 24.
10-23 12:45:55.228 3183 5853 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_metadata.bin with size 105160.
10-23 12:45:55.561 3183 5853 I SystemUpdate: [Execution,UpdateEngineDelegate] verifyPayloadMetadata()
10-23 12:45:55.562 1058 1058 I update_engine: [1023/124555.562268:INFO:binder_service_android.cc(145)] Received a request of verifying payload metadata in /data/ota_package/payload_metadata.bin.
10-23 12:45:55.567 1058 1058 I update_engine: [1023/124555.567693:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 12:45:55.568 1058 1058 I update_engine: [1023/124555.568821:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 12:45:55.568 1058 1058 I update_engine: [1023/124555.568951:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 12:45:57.625 3183 5853 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/metadata with size 554.
10-23 12:45:57.635 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action pre-download-validate executed for 5.51 seconds.
10-23 12:45:57.690 3183 5853 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
10-23 12:45:57.759 3183 5853 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 12:45:57.763 3183 5931 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 12:45:57.765 3183 3672 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 12:45:57.773 3183 3732 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 12:45:57.784 3183 8449 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 12:45:57.839 3183 8449 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 12:46:00.230 3183 5853 I SystemUpdate: [Execution,PackageFiles] Allocating file /data/ota_package/payload_properties.txt with size 156.
10-23 12:46:01.584 3183 5853 I SystemUpdate: [Execution,PackageFiles] Allocating space for file /data/ota_package/care_map.pb with size 561.
10-23 12:46:03.527 3183 5853 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 554.
10-23 12:46:03.537 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 5.90 seconds.
10-23 12:46:03.541 3183 5853 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x003, -1.000).
10-23 12:46:03.617 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action streaming-process-package executed for 0.08 seconds.
10-23 12:46:03.656 3183 5853 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 12:46:03.714 3183 5853 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:46:03.720 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.10 seconds.
10-23 12:46:03.730 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 12:46:03.734 3183 5853 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 12:46:03.759 3183 5853 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:46:03.761 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.03 seconds.
10-23 12:46:03.767 3183 5853 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.00 seconds.
10-23 12:46:10.970 3183 9561 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 12:46:10.978 3183 9559 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:46:10.980 3183 8451 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 12:46:11.024 3183 8451 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 12:46:11.077 3183 8451 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:46:11.085 3183 8451 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.10 seconds.
10-23 12:46:11.099 3183 8451 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 12:46:17.028 3183 8978 I SystemUpdate: [Control,ChimeraGcmTaskService] Task started with tag: DeviceIdle.
10-23 12:46:17.028 3183 8978 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:46:17.054 3183 9565 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 5.
10-23 12:46:17.056 3183 8449 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 12:46:17.122 3183 8449 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
10-23 12:46:17.173 3183 8449 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 12:46:17.175 3183 5931 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 12:46:17.181 3183 3732 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 12:46:17.181 3183 3672 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 12:46:17.196 3183 9565 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 12:46:17.221 3183 9565 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 12:46:17.237 3183 9558 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 12:46:17.244 3183 8449 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=4)
10-23 12:46:17.244 3183 8449 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 12:46:17.250 1058 1058 I update_engine: [1023/124617.250511:INFO:prefs.cc(122)] update-state-next-operation not present in /data/misc/update_engine/prefs
10-23 12:46:17.256 1058 1058 I update_engine: [1023/124617.256414:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 12:46:17.256 1058 1058 I update_engine: [1023/124617.256594: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: false, run_post_install: true, is_rollback: false, write_verity: true
10-23 12:46:17.257 1058 1058 I update_engine: [1023/124617.257362:INFO:metrics_utils.cc(349)] Number of Reboots during current update attempt = 0
10-23 12:46:17.260 1058 1058 I update_engine: [1023/124617.260101:INFO:metrics_utils.cc(357)] Payload Attempt Number = 1
10-23 12:46:17.261 1058 1058 I update_engine: [1023/124617.260518:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:02:53 GMT
10-23 12:46:17.261 1058 1058 I update_engine: [1023/124617.261293:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 0:02:53 GMT
10-23 12:46:17.261 1058 1058 I update_engine: [1023/124617.261420:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 12:46:17.262 1058 1058 I update_engine: [1023/124617.261974:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 12:46:17.262 1058 1058 I update_engine: [1023/124617.262096:INFO:update_boot_flags_action.cc(45)] Marking booted slot as good.
10-23 12:46:17.279 3183 9558 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x002.
10-23 12:46:17.284 1058 1058 I update_engine: [1023/124617.284148:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 12:46:17.284 1058 1058 I update_engine: [1023/124617.284264:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 12:46:17.284 1058 1058 I update_engine: [1023/124617.284309:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 12:46:17.284 1058 1058 I update_engine: [1023/124617.284395:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 12:46:17.284 1058 1058 I update_engine: [1023/124617.284441: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: false, run_post_install: true, is_rollback: false, write_verity: true
10-23 12:46:17.284 1058 1058 I update_engine: [1023/124617.284484:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 12:46:17.302 1058 1058 I update_engine: [1023/124617.302649:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 12:46:17.302 1058 1058 I update_engine: [1023/124617.302763:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 1260+1576938951
10-23 12:46:17.302 1058 1058 I update_engine: [1023/124617.302874:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
10-23 12:46:17.305 1058 1058 I update_engine: [1023/124617.304923:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
10-23 12:46:17.305 1058 1058 I update_engine: [1023/124617.305163:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
10-23 12:46:18.452 1058 1058 I update_engine: [1023/124618.452279:INFO:prefs.cc(122)] update-server-cert-1-2 not present in /data/misc/update_engine/prefs
10-23 12:46:18.453 1058 1058 I update_engine: [1023/124618.453410:INFO:prefs.cc(122)] update-server-cert-1-1 not present in /data/misc/update_engine/prefs
10-23 12:46:18.455 1058 1058 I update_engine: [1023/124618.455376:INFO:prefs.cc(122)] update-server-cert-1-0 not present in /data/misc/update_engine/prefs
10-23 12:46:19.400 1058 1058 I update_engine: [1023/124619.400187:INFO:certificate_checker.cc(185)] Certificate changed from 2236258063ACF45D3624A0D641AE4DA4374B62AA6067D55E2494B63B970C5971 to 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD.
10-23 12:46:20.599 1058 1058 I update_engine: [1023/124620.599498:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 12:46:20.626 3183 4157 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 12:46:20.653 3183 4157 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:20.726 1058 1058 I update_engine: [1023/124620.726470:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 12:46:20.726 1058 1058 I update_engine: [1023/124620.726693:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 12:46:20.726 1058 1058 I update_engine: [1023/124620.726957:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 12:46:20.727 1058 1058 I update_engine: [1023/124620.727880:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 12:46:20.728 1058 1058 I update_engine: [1023/124620.728002:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 12:46:20.733 1058 1058 I update_engine: [1023/124620.733385:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 12:46:20.760 1058 1058 I update_engine: [1023/124620.760148:INFO:prefs.cc(122)] dynamic-partition-metadata-updated not present in /data/misc/update_engine/prefs
10-23 12:46:20.976 1058 1058 I update_engine: [1023/124620.976370:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 12:46:21.391 1058 1058 I update_engine: [1023/124621.391400:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 12:46:21.389 1058 1058 W update_engine: type=1400 audit(0.0:425): 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 12:46:21.395 1058 1058 I update_engine: [1023/124621.395286:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.395 1058 1058 I update_engine: [1023/124621.395448:INFO:boot_control_android.cc(312)] Removing group google_dynamic_partitions_b
10-23 12:46:21.395 1058 1058 I update_engine: [1023/124621.395531:INFO:boot_control_android.cc(343)] Added group google_dynamic_partitions_b with size 4873781248
10-23 12:46:21.395 1058 1058 I update_engine: [1023/124621.395648:INFO:boot_control_android.cc(360)] Added partition system_b to group google_dynamic_partitions_b with size 820408320
10-23 12:46:21.395 1058 1058 I update_engine: [1023/124621.395734:INFO:boot_control_android.cc(360)] Added partition vendor_b to group google_dynamic_partitions_b with size 550678528
10-23 12:46:21.395 1058 1058 I update_engine: [1023/124621.395815:INFO:boot_control_android.cc(360)] Added partition product_b to group google_dynamic_partitions_b with size 2044289024
10-23 12:46:21.396 1058 1058 I update_engine: [1023/124621.395888:INFO:boot_control_android.cc(360)] Added partition system_ext_b to group google_dynamic_partitions_b with size 231182336
10-23 12:46:21.404 1058 1058 I update_engine: [1023/124621.404028:INFO:dynamic_partition_control_android.cc(228)] Copied metadata to slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.405 1058 1058 I update_engine: [1023/124621.404790:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 12:46:21.402 1058 1058 W update_engine: type=1400 audit(0.0:426): 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 12:46:21.409 1058 1058 I update_engine: [1023/124621.409916:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.618 1058 1058 I update_engine: [1023/124621.618704: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 12:46:21.616 1058 1058 W update_engine: type=1400 audit(0.0:427): 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 12:46:21.620 1058 1058 I update_engine: [1023/124621.620895:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.621 1058 1058 I update_engine: [1023/124621.621378:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 12:46:21.622 1058 1058 I update_engine: [1023/124621.622140:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.619 1058 1058 W update_engine: type=1400 audit(0.0:428): 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 12:46:21.624 1058 1058 I update_engine: [1023/124621.624164:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.692 1058 1058 I update_engine: [1023/124621.692918: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 12:46:21.689 1058 1058 W update_engine: type=1400 audit(0.0:429): 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 12:46:21.695 1058 1058 I update_engine: [1023/124621.695657:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.696 1058 1058 I update_engine: [1023/124621.696365:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 12:46:21.697 1058 1058 I update_engine: [1023/124621.696966:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.699 1058 1058 I update_engine: [1023/124621.699760:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.700 1058 1058 I update_engine: [1023/124621.700305:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 12:46:21.701 1058 1058 I update_engine: [1023/124621.701046:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.703 1058 1058 I update_engine: [1023/124621.703872:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.763 1058 1058 I update_engine: [1023/124621.763730: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 12:46:21.766 1058 1058 I update_engine: [1023/124621.765972:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.825 1058 1058 I update_engine: [1023/124621.825174: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 12:46:21.830 1058 1058 I update_engine: [1023/124621.830623:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.831 1058 1058 I update_engine: [1023/124621.831778:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 12:46:21.834 1058 1058 I update_engine: [1023/124621.834403:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.836 1058 1058 I update_engine: [1023/124621.836255:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.836 1058 1058 I update_engine: [1023/124621.836687:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 12:46:21.837 1058 1058 I update_engine: [1023/124621.837585:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.840 1058 1058 I update_engine: [1023/124621.840466:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.841 1058 1058 I update_engine: [1023/124621.841018:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 12:46:21.841 1058 1058 I update_engine: [1023/124621.841796:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.844 1058 1058 I update_engine: [1023/124621.844523:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.845 1058 1058 I update_engine: [1023/124621.845114:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 12:46:21.845 1058 1058 I update_engine: [1023/124621.845785:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.847 1058 1058 I update_engine: [1023/124621.847664:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.848 1058 1058 I update_engine: [1023/124621.848236:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 12:46:21.848 1058 1058 I update_engine: [1023/124621.848952:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.850 1058 1058 I update_engine: [1023/124621.850816:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.851 1058 1058 I update_engine: [1023/124621.851191:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 12:46:21.851 1058 1058 I update_engine: [1023/124621.851803:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.853 1058 1058 I update_engine: [1023/124621.853372:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.853 1058 1058 I update_engine: [1023/124621.853879:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 12:46:21.854 1058 1058 I update_engine: [1023/124621.854489:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.856 1058 1058 I update_engine: [1023/124621.856222:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.856 1058 1058 I update_engine: [1023/124621.856712:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 12:46:21.857 1058 1058 I update_engine: [1023/124621.857321:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.859 1058 1058 I update_engine: [1023/124621.858987:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.859 1058 1058 I update_engine: [1023/124621.859391:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 12:46:21.860 1058 1058 I update_engine: [1023/124621.859978:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.863 1058 1058 I update_engine: [1023/124621.863083:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.863 1058 1058 I update_engine: [1023/124621.863551:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 12:46:21.864 1058 1058 I update_engine: [1023/124621.864149:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.865 1058 1058 I update_engine: [1023/124621.865901:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.866 1058 1058 I update_engine: [1023/124621.866412:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 12:46:21.867 1058 1058 I update_engine: [1023/124621.867066:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.868 1058 1058 I update_engine: [1023/124621.868846:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 12:46:21.869 1058 1058 I update_engine: [1023/124621.869348:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.869971:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870152:INFO:delta_performer.cc(450)] PartitionInfo new vendor sha256: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4= size: 550678528
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870227:INFO:delta_performer.cc(450)] PartitionInfo new boot sha256: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU= size: 67108864
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870269:INFO:delta_performer.cc(450)] PartitionInfo new system sha256: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q= size: 820408320
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870304:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta sha256: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg= size: 8192
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870337:INFO:delta_performer.cc(450)] PartitionInfo new dtbo sha256: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c= size: 8388608
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870370:INFO:delta_performer.cc(450)] PartitionInfo new product sha256: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA= size: 2044289024
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870403:INFO:delta_performer.cc(450)] PartitionInfo new system_ext sha256: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM= size: 231182336
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870480:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta_system sha256: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA= size: 4096
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870519:INFO:delta_performer.cc(450)] PartitionInfo new abl sha256: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA= size: 1048576
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870552:INFO:delta_performer.cc(450)] PartitionInfo new aop sha256: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk= size: 167936
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870585:INFO:delta_performer.cc(450)] PartitionInfo new devcfg sha256: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE= size: 45056
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870619:INFO:delta_performer.cc(450)] PartitionInfo new hyp sha256: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w= size: 397312
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870653:INFO:delta_performer.cc(450)] PartitionInfo new keymaster sha256: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0= size: 241664
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870686:INFO:delta_performer.cc(450)] PartitionInfo new qupfw sha256: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w= size: 53248
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870718:INFO:delta_performer.cc(450)] PartitionInfo new tz sha256: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg= size: 2060288
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870751:INFO:delta_performer.cc(450)] PartitionInfo new uefisecapp sha256: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo= size: 126976
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870786:INFO:delta_performer.cc(450)] PartitionInfo new xbl sha256: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE= size: 3371008
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870819:INFO:delta_performer.cc(450)] PartitionInfo new xbl_config sha256: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY= size: 90112
10-23 12:46:21.870 1058 1058 I update_engine: [1023/124621.870853:INFO:delta_performer.cc(450)] PartitionInfo new modem sha256: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE= size: 74108928
10-23 12:46:21.871 1058 1058 I update_engine: [1023/124621.871183:INFO:delta_performer.cc(384)] Opening /dev/block/dm-1 partition without O_DSYNC
10-23 12:46:21.872 1058 1058 I update_engine: [1023/124621.872775:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:46:21.872 1058 1058 I update_engine: [1023/124621.872964:INFO:delta_performer.cc(396)] Applying 263 operations to partition "vendor"
10-23 12:46:21.873 1058 1058 I update_engine: [1023/124621.873016:INFO:delta_performer.cc(654)] Starting to apply update payload operations
10-23 12:46:25.801 3183 9558 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.010).
10-23 12:46:25.860 3183 9558 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:28.385 3183 5853 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 12:46:28.414 3183 10748 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:46:28.870 3183 3672 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 12:46:28.902 3183 9565 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 12:46:30.654 3183 6275 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.020).
10-23 12:46:30.691 3183 6275 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:35.512 3183 3858 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.030).
10-23 12:46:35.561 3183 3858 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:37.643 3183 11289 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 12:46:37.688 3183 11288 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:46:40.689 3183 6165 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.040).
10-23 12:46:40.754 3183 6165 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:45.321 3183 6165 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.050).
10-23 12:46:45.361 3183 6165 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:49.665 3183 9558 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.060).
10-23 12:46:49.703 3183 9558 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:46:50.706 1058 1058 I update_engine: [1023/124650.706023:INFO:delta_performer.cc(208)] Completed 164/1826 operations (8%), 98235608/1576938951 bytes downloaded (6%), overall progress 7%
10-23 12:46:54.479 3183 9558 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.070).
10-23 12:46:54.518 3183 9558 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 12:47:03.504 1058 1058 I update_engine: [1023/124703.504421:INFO:delta_performer.cc(208)] Completed 220/1826 operations (12%), 138933150/1576938951 bytes downloaded (8%), overall progress 10%
10-23 12:47:11.459 1058 1058 I update_engine: [1023/124711.459670:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/boot_b partition without O_DSYNC
10-23 12:47:11.461 1058 1058 I update_engine: [1023/124711.461398:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:47:11.461 1058 1058 I update_engine: [1023/124711.461700:INFO:delta_performer.cc(396)] Applying 32 operations to partition "boot"
10-23 12:47:20.094 1058 1058 I update_engine: [1023/124720.094281:INFO:delta_performer.cc(384)] Opening /dev/block/dm-4 partition without O_DSYNC
10-23 12:47:20.096 1058 1058 I update_engine: [1023/124720.095962:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:47:20.096 1058 1058 I update_engine: [1023/124720.096164:INFO:delta_performer.cc(396)] Applying 392 operations to partition "system"
10-23 12:47:29.065 11610 11762 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 12:47:29.238 11610 11762 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: LogStatus.
10-23 12:47:29.599 11610 11674 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 1.
10-23 12:47:29.884 11610 11674 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 12:47:30.254 11610 11674 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 12:47:30.873 11610 11674 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:47:30.881 11610 11674 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 1.00 seconds.
10-23 12:47:30.897 11610 11674 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 12:47:33.603 1058 1058 I update_engine: [1023/124733.603519:INFO:delta_performer.cc(208)] Completed 371/1826 operations (20%), 239005862/1576938951 bytes downloaded (15%), overall progress 17%
10-23 12:47:37.498 11610 12306 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 12:47:37.536 11610 12318 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:47:37.595 11610 12318 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 12:47:37.720 11610 12318 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 12:47:37.830 11610 12318 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:47:37.832 11610 12318 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.24 seconds.
10-23 12:47:37.844 11610 12318 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 12:47:45.750 1058 1058 I update_engine: [1023/124745.750840:INFO:delta_performer.cc(208)] Completed 439/1826 operations (24%), 279342949/1576938951 bytes downloaded (17%), overall progress 20%
10-23 12:47:52.067 11610 12723 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 12:47:52.086 11610 12722 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:47:52.096 11610 12724 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 12:47:52.145 11610 12724 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 12:47:52.240 11610 12724 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:47:52.242 11610 12724 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.14 seconds.
10-23 12:47:52.254 11610 12724 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 12:48:15.832 1058 1058 I update_engine: [1023/124815.832505:INFO:delta_performer.cc(208)] Completed 585/1826 operations (32%), 406776743/1576938951 bytes downloaded (25%), overall progress 28%
10-23 12:48:21.448 1058 1058 I update_engine: [1023/124821.448401:INFO:delta_performer.cc(208)] Completed 621/1826 operations (34%), 432122589/1576938951 bytes downloaded (27%), overall progress 30%
10-23 12:48:32.331 1058 1058 I update_engine: [1023/124832.331493:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/vbmeta_b partition without O_DSYNC
10-23 12:48:32.333 1058 1058 I update_engine: [1023/124832.333292:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:48:32.333 1058 1058 I update_engine: [1023/124832.333505:INFO:delta_performer.cc(396)] Applying 1 operations to partition "vbmeta"
10-23 12:48:32.337 1058 1058 I update_engine: [1023/124832.337266:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/dtbo_b partition without O_DSYNC
10-23 12:48:32.338 1058 1058 I update_engine: [1023/124832.338870:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:48:32.339 1058 1058 I update_engine: [1023/124832.339093:INFO:delta_performer.cc(396)] Applying 4 operations to partition "dtbo"
10-23 12:48:32.434 1058 1058 I update_engine: [1023/124832.434753:INFO:delta_performer.cc(384)] Opening /dev/block/dm-9 partition without O_DSYNC
10-23 12:48:32.436 1058 1058 I update_engine: [1023/124832.436393:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:48:32.436 1058 1058 I update_engine: [1023/124832.436554:INFO:delta_performer.cc(396)] Applying 975 operations to partition "product"
10-23 12:48:51.497 1058 1058 I update_engine: [1023/124851.497645:INFO:delta_performer.cc(208)] Completed 788/1826 operations (43%), 570795702/1576938951 bytes downloaded (36%), overall progress 39%
10-23 12:48:54.877 1058 1058 I update_engine: [1023/124854.877618:INFO:delta_performer.cc(208)] Completed 804/1826 operations (44%), 589129269/1576938951 bytes downloaded (37%), overall progress 40%
10-23 12:49:24.926 1058 1058 I update_engine: [1023/124924.926759:INFO:delta_performer.cc(208)] Completed 917/1826 operations (50%), 715138500/1576938951 bytes downloaded (45%), overall progress 47%
10-23 12:49:41.279 1058 1058 I update_engine: [1023/124941.279274:INFO:delta_performer.cc(208)] Completed 950/1826 operations (52%), 782196856/1576938951 bytes downloaded (49%), overall progress 50%
10-23 12:50:11.385 1058 1058 I update_engine: [1023/125011.384794:INFO:delta_performer.cc(208)] Completed 1086/1826 operations (59%), 909401253/1576938951 bytes downloaded (57%), overall progress 57%
10-23 12:50:19.890 1058 1058 I update_engine: [1023/125019.890592:INFO:delta_performer.cc(208)] Completed 1110/1826 operations (60%), 946166663/1576938951 bytes downloaded (60%), overall progress 60%
10-23 12:50:49.904 1058 1058 I update_engine: [1023/125049.904723:INFO:delta_performer.cc(208)] Completed 1233/1826 operations (67%), 1072994243/1576938951 bytes downloaded (68%), overall progress 67%
10-23 12:50:56.764 1058 1058 I update_engine: [1023/125056.764543:INFO:delta_performer.cc(208)] Completed 1279/1826 operations (70%), 1104484052/1576938951 bytes downloaded (70%), overall progress 70%
10-23 12:51:26.784 1058 1058 I update_engine: [1023/125126.784048:INFO:delta_performer.cc(208)] Completed 1431/1826 operations (78%), 1241125572/1576938951 bytes downloaded (78%), overall progress 78%
10-23 12:51:37.039 1058 1058 I update_engine: [1023/125137.039387:INFO:delta_performer.cc(208)] Completed 1461/1826 operations (80%), 1275498938/1576938951 bytes downloaded (80%), overall progress 80%
10-23 12:52:07.193 1058 1058 I update_engine: [1023/125207.193686:INFO:delta_performer.cc(208)] Completed 1584/1826 operations (86%), 1372294877/1576938951 bytes downloaded (87%), overall progress 86%
10-23 12:52:20.036 1058 1058 I update_engine: [1023/125220.036117:INFO:delta_performer.cc(208)] Completed 1644/1826 operations (90%), 1423936852/1576938951 bytes downloaded (90%), overall progress 90%
10-23 12:52:26.597 1058 1058 I update_engine: [1023/125226.596994:INFO:delta_performer.cc(384)] Opening /dev/block/dm-10 partition without O_DSYNC
10-23 12:52:26.598 1058 1058 I update_engine: [1023/125226.598704:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:26.598 1058 1058 I update_engine: [1023/125226.598874:INFO:delta_performer.cc(396)] Applying 111 operations to partition "system_ext"
10-23 12:52:45.535 1058 1058 I update_engine: [1023/125245.535186:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/vbmeta_system_b partition without O_DSYNC
10-23 12:52:45.537 1058 1058 I update_engine: [1023/125245.537033:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.537 1058 1058 I update_engine: [1023/125245.537318:INFO:delta_performer.cc(396)] Applying 1 operations to partition "vbmeta_system"
10-23 12:52:45.540 1058 1058 I update_engine: [1023/125245.540426:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/abl_b partition without O_DSYNC
10-23 12:52:45.542 1058 1058 I update_engine: [1023/125245.542160:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.542 1058 1058 I update_engine: [1023/125245.542364:INFO:delta_performer.cc(396)] Applying 1 operations to partition "abl"
10-23 12:52:45.585 1058 1058 I update_engine: [1023/125245.585895:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/aop_b partition without O_DSYNC
10-23 12:52:45.588 1058 1058 I update_engine: [1023/125245.588022:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.588 1058 1058 I update_engine: [1023/125245.588283:INFO:delta_performer.cc(396)] Applying 1 operations to partition "aop"
10-23 12:52:45.607 1058 1058 I update_engine: [1023/125245.607190:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/devcfg_b partition without O_DSYNC
10-23 12:52:45.608 1058 1058 I update_engine: [1023/125245.608798:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.609 1058 1058 I update_engine: [1023/125245.609007:INFO:delta_performer.cc(396)] Applying 1 operations to partition "devcfg"
10-23 12:52:45.615 1058 1058 I update_engine: [1023/125245.615097:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/hyp_b partition without O_DSYNC
10-23 12:52:45.616 1058 1058 I update_engine: [1023/125245.616624:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.616 1058 1058 I update_engine: [1023/125245.616846:INFO:delta_performer.cc(396)] Applying 1 operations to partition "hyp"
10-23 12:52:45.645 1058 1058 I update_engine: [1023/125245.645360:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/keymaster_b partition without O_DSYNC
10-23 12:52:45.646 1058 1058 I update_engine: [1023/125245.646906:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.647 1058 1058 I update_engine: [1023/125245.647135:INFO:delta_performer.cc(396)] Applying 1 operations to partition "keymaster"
10-23 12:52:45.670 1058 1058 I update_engine: [1023/125245.670234:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/qupfw_b partition without O_DSYNC
10-23 12:52:45.671 1058 1058 I update_engine: [1023/125245.671763:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.671 1058 1058 I update_engine: [1023/125245.671949:INFO:delta_performer.cc(396)] Applying 1 operations to partition "qupfw"
10-23 12:52:45.682 1058 1058 I update_engine: [1023/125245.682780:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/tz_b partition without O_DSYNC
10-23 12:52:45.684 1058 1058 I update_engine: [1023/125245.684464:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.684 1058 1058 I update_engine: [1023/125245.684702:INFO:delta_performer.cc(396)] Applying 1 operations to partition "tz"
10-23 12:52:45.850 1058 1058 I update_engine: [1023/125245.850626:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/uefisecapp_b partition without O_DSYNC
10-23 12:52:45.852 1058 1058 I update_engine: [1023/125245.852769:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.853 1058 1058 I update_engine: [1023/125245.853148:INFO:delta_performer.cc(396)] Applying 1 operations to partition "uefisecapp"
10-23 12:52:45.870 1058 1058 I update_engine: [1023/125245.869985:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/xbl_b partition without O_DSYNC
10-23 12:52:45.871 1058 1058 I update_engine: [1023/125245.871615:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:45.871 1058 1058 I update_engine: [1023/125245.871838:INFO:delta_performer.cc(396)] Applying 2 operations to partition "xbl"
10-23 12:52:46.221 1058 1058 I update_engine: [1023/125246.221416:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/xbl_config_b partition without O_DSYNC
10-23 12:52:46.222 1058 1058 I update_engine: [1023/125246.222940:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:46.223 1058 1058 I update_engine: [1023/125246.223126:INFO:delta_performer.cc(396)] Applying 1 operations to partition "xbl_config"
10-23 12:52:46.234 1058 1058 I update_engine: [1023/125246.234869:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/modem_b partition without O_DSYNC
10-23 12:52:46.236 1058 1058 I update_engine: [1023/125246.236431:INFO:delta_performer.cc(127)] Caching writes.
10-23 12:52:46.236 1058 1058 I update_engine: [1023/125246.236621:INFO:delta_performer.cc(396)] Applying 36 operations to partition "modem"
10-23 12:52:50.297 1058 1058 I update_engine: [1023/125250.297358:INFO:delta_performer.cc(208)] Completed 1815/1826 operations (99%), 1558726980/1576938951 bytes downloaded (98%), overall progress 98%
10-23 12:52:53.445 1058 1058 I update_engine: [1023/125253.445780:INFO:delta_performer.cc(208)] Completed 1826/1826 operations (100%), 1576938951/1576938951 bytes downloaded (100%), overall progress 100%
10-23 12:52:53.448 1058 1058 I update_engine: [1023/125253.448387:INFO:delta_performer.cc(1602)] Extracted signature data of size 523 at 1576833268
10-23 12:52:53.449 1058 1058 I update_engine: [1023/125253.449104:INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
10-23 12:52:53.449 1058 1058 I update_engine: [1023/125253.449211:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
10-23 12:52:53.456 1058 1058 I update_engine: [1023/125253.455982:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 12:52:53.456 1058 1058 I update_engine: [1023/125253.456862:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
10-23 12:52:53.456 1058 1058 I update_engine: [1023/125253.456942:INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
10-23 12:52:53.578 1058 1058 I update_engine: [1023/125253.578447:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 12:52:53.578 1058 1058 I update_engine: [1023/125253.578690:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 12:52:53.579 1058 1058 I update_engine: [1023/125253.579623:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 12:52:53.579 1058 1058 I update_engine: [1023/125253.579753:INFO:delta_performer.cc(1829)] Payload hash matches value in payload.
10-23 12:52:53.579 1058 1058 I update_engine: [1023/125253.579913:INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
10-23 12:52:53.579 1058 1058 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 1826 samples, mean = 126.6
10-23 12:52:53.579 1058 1058 I update_engine: 0 --O (21 = 1.2%)
10-23 12:52:53.579 1058 1058 I update_engine: 10 --------O (101 = 5.5%) {1.2%}
10-23 12:52:53.579 1058 1058 I update_engine: 18 ------O (77 = 4.2%) {6.7%}
10-23 12:52:53.579 1058 1058 I update_engine: 32 ---O (41 = 2.2%) {10.9%}
10-23 12:52:53.579 1058 1058 I update_engine: 57 ----------------------------O (355 = 19.4%) {13.1%}
10-23 12:52:53.579 1058 1058 I update_engine: 101 ------------------------------------------------------------------------O (923 = 50.5%) {32.6%}
10-23 12:52:53.579 1058 1058 I update_engine: 179 ----------------------O (288 = 15.8%) {83.1%}
10-23 12:52:53.579 1058 1058 I update_engine: 317 --O (20 = 1.1%) {98.9%}
10-23 12:52:53.579 1058 1058 I update_engine: 561 ...
10-23 12:52:53.579 1058 1058 I update_engine:
10-23 12:52:53.579 1058 1058 I update_engine:
10-23 12:52:53.587 1058 1058 I update_engine: [1023/125253.587158:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
10-23 12:52:53.587 1058 1058 I update_engine: [1023/125253.587322:INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
10-23 12:52:53.588 1058 1058 I update_engine: [1023/125253.588492:INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (vendor) on device /dev/block/dm-1
10-23 12:52:55.371 1058 1058 I update_engine: [1023/125255.371322:INFO:filesystem_verifier_action.cc(237)] Hash of vendor: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4=
10-23 12:52:55.545 1058 1058 I update_engine: [1023/125255.545265:INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (boot) on device /dev/block/bootdevice/by-name/boot_b
10-23 12:52:55.806 1058 1058 I update_engine: [1023/125255.806605:INFO:filesystem_verifier_action.cc(237)] Hash of boot: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU=
10-23 12:52:55.826 1058 1058 I update_engine: [1023/125255.826829:INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (system) on device /dev/block/dm-4
10-23 12:52:58.888 1058 1058 I update_engine: [1023/125258.888582:INFO:filesystem_verifier_action.cc(237)] Hash of system: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q=
10-23 12:52:59.124 1058 1058 I update_engine: [1023/125259.124869:INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (vbmeta) on device /dev/block/bootdevice/by-name/vbmeta_b
10-23 12:52:59.126 1058 1058 I update_engine: [1023/125259.126719:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg=
10-23 12:52:59.126 1058 1058 I update_engine: [1023/125259.126947:INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (dtbo) on device /dev/block/bootdevice/by-name/dtbo_b
10-23 12:52:59.155 1058 1058 I update_engine: [1023/125259.155586:INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c=
10-23 12:52:59.158 1058 1058 I update_engine: [1023/125259.158469:INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (product) on device /dev/block/dm-9
10-23 12:53:07.166 1058 1058 I update_engine: [1023/125307.166323:INFO:filesystem_verifier_action.cc(237)] Hash of product: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA=
10-23 12:53:07.739 1058 1058 I update_engine: [1023/125307.739162:INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (system_ext) on device /dev/block/dm-10
10-23 12:53:08.607 1058 1058 I update_engine: [1023/125308.607422:INFO:filesystem_verifier_action.cc(237)] Hash of system_ext: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM=
10-23 12:53:08.673 1058 1058 I update_engine: [1023/125308.673649:INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (vbmeta_system) on device /dev/block/bootdevice/by-name/vbmeta_system_b
10-23 12:53:08.675 1058 1058 I update_engine: [1023/125308.675154:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_system: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA=
10-23 12:53:08.675 1058 1058 I update_engine: [1023/125308.675349:INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (abl) on device /dev/block/bootdevice/by-name/abl_b
10-23 12:53:08.681 1058 1058 I update_engine: [1023/125308.681187:INFO:filesystem_verifier_action.cc(237)] Hash of abl: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA=
10-23 12:53:08.681 1058 1058 I update_engine: [1023/125308.681709:INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (aop) on device /dev/block/bootdevice/by-name/aop_b
10-23 12:53:08.684 1058 1058 I update_engine: [1023/125308.684009:INFO:filesystem_verifier_action.cc(237)] Hash of aop: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk=
10-23 12:53:08.684 1058 1058 I update_engine: [1023/125308.684498:INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (devcfg) on device /dev/block/bootdevice/by-name/devcfg_b
10-23 12:53:08.685 1058 1058 I update_engine: [1023/125308.685858:INFO:filesystem_verifier_action.cc(237)] Hash of devcfg: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE=
10-23 12:53:08.686 1058 1058 I update_engine: [1023/125308.686054:INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (hyp) on device /dev/block/bootdevice/by-name/hyp_b
10-23 12:53:08.689 1058 1058 I update_engine: [1023/125308.689540:INFO:filesystem_verifier_action.cc(237)] Hash of hyp: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w=
10-23 12:53:08.689 1058 1058 I update_engine: [1023/125308.689872:INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (keymaster) on device /dev/block/bootdevice/by-name/keymaster_b
10-23 12:53:08.692 1058 1058 I update_engine: [1023/125308.691942:INFO:filesystem_verifier_action.cc(237)] Hash of keymaster: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0=
10-23 12:53:08.692 1058 1058 I update_engine: [1023/125308.692216:INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (qupfw) on device /dev/block/bootdevice/by-name/qupfw_b
10-23 12:53:08.693 1058 1058 I update_engine: [1023/125308.693339:INFO:filesystem_verifier_action.cc(237)] Hash of qupfw: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w=
10-23 12:53:08.693 1058 1058 I update_engine: [1023/125308.693509:INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (tz) on device /dev/block/bootdevice/by-name/tz_b
10-23 12:53:08.702 1058 1058 I update_engine: [1023/125308.702529:INFO:filesystem_verifier_action.cc(237)] Hash of tz: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg=
10-23 12:53:08.703 1058 1058 I update_engine: [1023/125308.703487:INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (uefisecapp) on device /dev/block/bootdevice/by-name/uefisecapp_b
10-23 12:53:08.705 1058 1058 I update_engine: [1023/125308.705427:INFO:filesystem_verifier_action.cc(237)] Hash of uefisecapp: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo=
10-23 12:53:08.705 1058 1058 I update_engine: [1023/125308.705634:INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (xbl) on device /dev/block/bootdevice/by-name/xbl_b
10-23 12:53:08.720 1058 1058 I update_engine: [1023/125308.720112:INFO:filesystem_verifier_action.cc(237)] Hash of xbl: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE=
10-23 12:53:08.721 1058 1058 I update_engine: [1023/125308.721473:INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (xbl_config) on device /dev/block/bootdevice/by-name/xbl_config_b
10-23 12:53:08.723 1058 1058 I update_engine: [1023/125308.723323:INFO:filesystem_verifier_action.cc(237)] Hash of xbl_config: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY=
10-23 12:53:08.723 1058 1058 I update_engine: [1023/125308.723517:INFO:filesystem_verifier_action.cc(117)] Hashing partition 18 (modem) on device /dev/block/bootdevice/by-name/modem_b
10-23 12:53:08.993 1058 1058 I update_engine: [1023/125308.993048:INFO:filesystem_verifier_action.cc(237)] Hash of modem: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE=
10-23 12:53:09.017 1058 1058 I update_engine: [1023/125309.017050:INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
10-23 12:53:09.017 1058 1058 I update_engine: [1023/125309.017196:INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
10-23 12:53:09.021 1058 1058 I update_engine: [1023/125309.021558: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 12:53:09.023 1058 1058 I update_engine: [1023/125309.021708:INFO:postinstall_runner_action.cc(179)] Format file for new bin/checkpoint_gc is: data
10-23 12:53:09.096 1058 1058 I update_engine: [1023/125309.096397:INFO:subprocess.cc(157)] Subprocess output:
10-23 12:53:09.096 1058 1058 I update_engine: realpath: /dev/block/dm-8: Permission denied
10-23 12:53:09.096 1058 1058 I update_engine:
10-23 12:53:09.101 1058 1058 I update_engine: [1023/125309.101799: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 12:53:09.105 1058 1058 I update_engine: [1023/125309.101998:INFO:postinstall_runner_action.cc(179)] Format file for new system/bin/otapreopt_script is: data
10-23 12:54:20.509 11610 16558 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 12:54:20.559 11610 16564 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 12:54:20.570 11610 16563 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 12:54:20.830 11610 16563 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 12:54:20.996 11610 16563 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 12:54:20.998 11610 16563 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.42 seconds.
10-23 12:54:21.026 11610 16563 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 12:58:27.892 1058 1058 I update_engine: [1023/125827.892002:INFO:subprocess.cc(157)] Subprocess output:
10-23 12:58:27.892 1058 1058 I update_engine: Complete or error.
10-23 12:58:27.892 1058 1058 I update_engine:
10-23 12:58:27.895 1058 1058 I update_engine: [1023/125827.895573:INFO:postinstall_runner_action.cc(364)] All post-install commands succeeded
10-23 12:58:27.896 1058 1058 I update_engine: [1023/125827.896819:INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kUpdatedButNotActive
10-23 12:58:27.896 1058 1058 I update_engine: [1023/125827.896914:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 12:58:27.896 1058 1058 I update_engine: [1023/125827.896966:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, system_ext_b, vendor_b] from device mapper
10-23 12:58:28.133 1058 1058 I update_engine: [1023/125828.133165:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 12:58:28.551 1058 1058 I update_engine: [1023/125828.551043:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 12:58:28.968 1058 1058 I update_engine: [1023/125828.968161:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_ext_b from device mapper.
10-23 12:58:29.387 1058 1058 I update_engine: [1023/125829.387619:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 12:58:29.403 1058 1058 I update_engine: [1023/125829.403070:INFO:metrics_reporter_android.cc(29)] uploading 1 to histogram for metric ota_update_engine_attempt_number
10-23 12:58:29.403 1058 1058 I update_engine: [1023/125829.403658:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 12:58:29.404 1058 1058 I update_engine: [1023/125829.404021:INFO:metrics_reporter_android.cc(29)] uploading 12 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 12:58:29.404 1058 1058 I update_engine: [1023/125829.404374:INFO:metrics_reporter_android.cc(29)] uploading 12 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 12:58:29.404 1058 1058 I update_engine: [1023/125829.404720:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 12:58:29.405 1058 1058 I update_engine: [1023/125829.405152:INFO:metrics_reporter_android.cc(29)] uploading 12 to histogram for metric ota_update_engine_attempt_result
10-23 12:58:29.405 1058 1058 I update_engine: [1023/125829.405503:INFO:metrics_reporter_android.cc(29)] uploading 52 to histogram for metric ota_update_engine_attempt_error_code
10-23 12:58:29.406 1058 1058 I update_engine: [1023/125829.406139:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 13:03:18.430 11610 22295 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:03:18.437 11610 22296 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:03:18.480 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x413, isSetupWizard=false.
10-23 13:03:18.528 11610 22296 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
10-23 13:03:18.639 11610 22296 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
10-23 13:03:18.643 11610 22296 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.20 seconds.
10-23 13:03:18.655 11610 22296 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
10-23 13:03:34.812 11610 22313 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 109.
10-23 13:03:34.818 11610 22314 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:03:34.871 11610 22314 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
10-23 13:03:34.899 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x111, isSetupWizard=false.
10-23 13:03:34.996 11610 22314 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
10-23 13:03:34.999 11610 12295 I SystemUpdate: [Network,NetworkRequester] Network acquired.
10-23 13:03:35.004 11610 17927 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:03:35.004 11610 11950 I SystemUpdate: [Control,NetworkControl] Received intent: Intent { act=android.net.wifi.supplicant.STATE_CHANGE flg=0x24000010 (has extras) }.
10-23 13:03:35.017 11610 22313 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 3.
10-23 13:03:35.093 11610 22313 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:03:35.120 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 13:03:35.176 11610 22314 I SystemUpdate: [Execution,SynchronizedUpdateEngine] start(ayce{url=https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload_binary_file.offset=1260, payload_binary_file.length=1576938951, properties=[FILE_HASH=yNdMzP4nHXPaSqS1D97Atji7R6JYwNRdRCyYebkY9+s=, FILE_SIZE=1576938951, METADATA_HASH=s4Rs4XtVL4Bzt4PV1NVNJoZWvqwa63LQMnGYpc4WPi0=, METADATA_SIZE=104637]}, additionalProperties.size=4)
10-23 13:03:35.177 11610 22314 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 13:03:35.195 1058 1058 I update_engine: [1023/130335.195552:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 13:03:35.196 1058 1058 I update_engine: [1023/130335.195996:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: false, run_post_install: true, is_rollback: false, write_verity: false
10-23 13:03:35.196 1058 1058 I update_engine: [1023/130335.196726:INFO:metrics_utils.cc(357)] Payload Attempt Number = 2
10-23 13:03:35.197 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x002.
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197099:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:20:11 GMT
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197339:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 0:20:11 GMT
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197406:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197678:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197742:INFO:update_boot_flags_action.cc(36)] Already updated boot flags. Skipping.
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197795:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197847:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197903:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 13:03:35.197 1058 1058 I update_engine: [1023/130335.197954:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 13:03:35.198 1058 1058 I update_engine: [1023/130335.198014:INFO:install_plan.cc(83)] InstallPlan: resume, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip, payload: (size: 1576938951, metadata_size: 104637, metadata signature: , hash: C8D74CCCFE271D73DA4AA4B50FDEC0B638BB47A258C0D45D442C9879B918F7EB, payload type: unknown), hash_checks_mandatory: true, powerwash_required: false, switch_slot_on_reboot: false, run_post_install: true, is_rollback: false, write_verity: false
10-23 13:03:35.198 1058 1058 I update_engine: [1023/130335.198192:INFO:prefs.cc(122)] update-state-payload-index not present in /data/misc/update_engine/prefs
10-23 13:03:35.198 1058 1058 I update_engine: [1023/130335.198257:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 13:03:35.238 1058 1058 I update_engine: [1023/130335.238497:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 13:03:35.238 1058 1058 I update_engine: [1023/130335.238646:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 1260+105160
10-23 13:03:35.238 1058 1058 I update_engine: [1023/130335.238822:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
10-23 13:03:35.240 1058 1058 I update_engine: [1023/130335.240194:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
10-23 13:03:35.240 1058 1058 I update_engine: [1023/130335.240362:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
10-23 13:03:36.496 1058 1058 I update_engine: [1023/130336.496150:INFO:certificate_checker.cc(185)] Certificate changed from 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD to 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0.
10-23 13:03:37.348 1058 1058 I update_engine: [1023/130337.348691:INFO:certificate_checker.cc(185)] Certificate changed from 7EF07575B8E8077EA41DF2C3C424C8D2F63AF534BEA01C46BA791F84B005BBE0 to 1237CBF5641AA75FE4D916B010F32BAF4E7BEA02EF175ADFD97BD10477E261AD.
10-23 13:03:38.025 1058 1058 I update_engine: [1023/130338.025780:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 13:03:38.031 11610 11725 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 13:03:38.081 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.000011.
10-23 13:03:38.156 1058 1058 I update_engine: [1023/130338.156308:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 13:03:38.156 1058 1058 I update_engine: [1023/130338.156540:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:03:38.156 1058 1058 I update_engine: [1023/130338.156811:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:03:38.157 1058 1058 I update_engine: [1023/130338.157867:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:03:38.157 1058 1058 I update_engine: [1023/130338.157929:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 13:03:38.165 1058 1058 I update_engine: [1023/130338.165520:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 13:03:38.172 11610 11725 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
10-23 13:03:38.190 1058 1058 I update_engine: [1023/130338.190327:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 13:03:38.189 1058 1058 W update_engine: type=1400 audit(0.0:455): 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:03:38.202 1058 1058 I update_engine: [1023/130338.202401:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.261 1058 1058 I update_engine: [1023/130338.261492: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:03:38.259 1058 1058 W update_engine: type=1400 audit(0.0:456): 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:03:38.264 1058 1058 I update_engine: [1023/130338.264137:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.264 1058 1058 I update_engine: [1023/130338.264653:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 13:03:38.265 1058 1058 I update_engine: [1023/130338.265289:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.262 1058 1058 W update_engine: type=1400 audit(0.0:457): 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:03:38.266 1058 1058 I update_engine: [1023/130338.266962:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.323 1058 1058 I update_engine: [1023/130338.323453: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:03:38.322 1058 1058 W update_engine: type=1400 audit(0.0:458): 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:03:38.328 1058 1058 I update_engine: [1023/130338.327902:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.328 1058 1058 I update_engine: [1023/130338.328830:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 13:03:38.330 1058 1058 I update_engine: [1023/130338.330098:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.329 1058 1058 W update_engine: type=1400 audit(0.0:459): 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:03:38.333 1058 1058 I update_engine: [1023/130338.333230:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.333 1058 1058 I update_engine: [1023/130338.333912:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 13:03:38.334 1058 1058 I update_engine: [1023/130338.334674:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.337 1058 1058 I update_engine: [1023/130338.337043:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.401 1058 1058 I update_engine: [1023/130338.401065: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:03:38.406 1058 1058 I update_engine: [1023/130338.405913:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.464 1058 1058 I update_engine: [1023/130338.464311: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:03:38.469 1058 1058 I update_engine: [1023/130338.469271:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.470 1058 1058 I update_engine: [1023/130338.470518:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 13:03:38.472 1058 1058 I update_engine: [1023/130338.472132:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.476 1058 1058 I update_engine: [1023/130338.476743:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.478 1058 1058 I update_engine: [1023/130338.477919:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 13:03:38.479 1058 1058 I update_engine: [1023/130338.479315:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.483 1058 1058 I update_engine: [1023/130338.483251:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.484 1058 1058 I update_engine: [1023/130338.484006:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 13:03:38.485 1058 1058 I update_engine: [1023/130338.485374:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.489 1058 1058 I update_engine: [1023/130338.489496:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.490 1058 1058 I update_engine: [1023/130338.490251:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 13:03:38.492 1058 1058 I update_engine: [1023/130338.492579:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.495 1058 1058 I update_engine: [1023/130338.495726:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.496 1058 1058 I update_engine: [1023/130338.496627:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 13:03:38.497 1058 1058 I update_engine: [1023/130338.497683:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.500 1058 1058 I update_engine: [1023/130338.500735:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.501 1058 1058 I update_engine: [1023/130338.501723:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 13:03:38.502 1058 1058 I update_engine: [1023/130338.502806:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.505 1058 1058 I update_engine: [1023/130338.505842:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.506 1058 1058 I update_engine: [1023/130338.506455:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 13:03:38.507 1058 1058 I update_engine: [1023/130338.507448:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.510 1058 1058 I update_engine: [1023/130338.510491:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.511 1058 1058 I update_engine: [1023/130338.511328:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 13:03:38.512 1058 1058 I update_engine: [1023/130338.512534:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.515 1058 1058 I update_engine: [1023/130338.515294:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.515 1058 1058 I update_engine: [1023/130338.515849:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 13:03:38.516 1058 1058 I update_engine: [1023/130338.516700:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.518 1058 1058 I update_engine: [1023/130338.518917:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.519 1058 1058 I update_engine: [1023/130338.519511:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 13:03:38.520 1058 1058 I update_engine: [1023/130338.520292:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.522 1058 1058 I update_engine: [1023/130338.522889:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.523 1058 1058 I update_engine: [1023/130338.523304:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 13:03:38.524 1058 1058 I update_engine: [1023/130338.524004:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.526 1058 1058 I update_engine: [1023/130338.526607:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:03:38.527 1058 1058 I update_engine: [1023/130338.527177:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 13:03:38.527 1058 1058 I update_engine: [1023/130338.527920:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528230:INFO:delta_performer.cc(450)] PartitionInfo new vendor sha256: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4= size: 550678528
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528324:INFO:delta_performer.cc(450)] PartitionInfo new boot sha256: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU= size: 67108864
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528390:INFO:delta_performer.cc(450)] PartitionInfo new system sha256: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q= size: 820408320
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528455:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta sha256: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg= size: 8192
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528518:INFO:delta_performer.cc(450)] PartitionInfo new dtbo sha256: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c= size: 8388608
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528582:INFO:delta_performer.cc(450)] PartitionInfo new product sha256: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA= size: 2044289024
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528710:INFO:delta_performer.cc(450)] PartitionInfo new system_ext sha256: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM= size: 231182336
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528782:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta_system sha256: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA= size: 4096
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528849:INFO:delta_performer.cc(450)] PartitionInfo new abl sha256: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA= size: 1048576
10-23 13:03:38.528 1058 1058 I update_engine: [1023/130338.528914:INFO:delta_performer.cc(450)] PartitionInfo new aop sha256: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk= size: 167936
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.528983:INFO:delta_performer.cc(450)] PartitionInfo new devcfg sha256: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE= size: 45056
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529049:INFO:delta_performer.cc(450)] PartitionInfo new hyp sha256: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w= size: 397312
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529114:INFO:delta_performer.cc(450)] PartitionInfo new keymaster sha256: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0= size: 241664
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529179:INFO:delta_performer.cc(450)] PartitionInfo new qupfw sha256: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w= size: 53248
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529245:INFO:delta_performer.cc(450)] PartitionInfo new tz sha256: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg= size: 2060288
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529309:INFO:delta_performer.cc(450)] PartitionInfo new uefisecapp sha256: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo= size: 126976
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529373:INFO:delta_performer.cc(450)] PartitionInfo new xbl sha256: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE= size: 3371008
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529439:INFO:delta_performer.cc(450)] PartitionInfo new xbl_config sha256: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY= size: 90112
10-23 13:03:38.529 1058 1058 I update_engine: [1023/130338.529502:INFO:delta_performer.cc(450)] PartitionInfo new modem sha256: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE= size: 74108928
10-23 13:03:38.530 1058 1058 I update_engine: [1023/130338.530696:INFO:delta_performer.cc(208)] Resuming after 1826/1826 operations (100%), 1576938951/1576938951 bytes downloaded (100%), overall progress 100%
10-23 13:03:38.530 1058 1058 I update_engine: [1023/130338.530793:INFO:delta_performer.cc(654)] Starting to apply update payload operations
10-23 13:03:38.530 1058 1058 I update_engine: [1023/130338.530863:INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
10-23 13:03:38.530 1058 1058 I update_engine: [1023/130338.530923:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
10-23 13:03:38.536 1058 1058 I update_engine: [1023/130338.536834:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 13:03:38.537 1058 1058 I update_engine: [1023/130338.537084:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
10-23 13:03:38.537 1058 1058 I update_engine: [1023/130338.537159:INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
10-23 13:03:38.537 1058 1058 I update_engine: [1023/130338.537377:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:03:38.537 1058 1058 I update_engine: [1023/130338.537563:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:03:38.539 1058 1058 I update_engine: [1023/130338.539194:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:03:38.539 1058 1058 I update_engine: [1023/130338.539357:INFO:delta_performer.cc(1829)] Payload hash matches value in payload.
10-23 13:03:38.539 1058 1058 I update_engine: [1023/130338.539705:INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
10-23 13:03:38.539 1058 1058 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 1826 samples, mean = 126.6
10-23 13:03:38.539 1058 1058 I update_engine: 0 --O (21 = 1.2%)
10-23 13:03:38.539 1058 1058 I update_engine: 10 --------O (101 = 5.5%) {1.2%}
10-23 13:03:38.539 1058 1058 I update_engine: 18 ------O (77 = 4.2%) {6.7%}
10-23 13:03:38.539 1058 1058 I update_engine: 32 ---O (41 = 2.2%) {10.9%}
10-23 13:03:38.539 1058 1058 I update_engine: 57 ----------------------------O (355 = 19.4%) {13.1%}
10-23 13:03:38.539 1058 1058 I update_engine: 101 ------------------------------------------------------------------------O (923 = 50.5%) {32.6%}
10-23 13:03:38.539 1058 1058 I update_engine: 179 ----------------------O (288 = 15.8%) {83.1%}
10-23 13:03:38.539 1058 1058 I update_engine: 317 --O (20 = 1.1%) {98.9%}
10-23 13:03:38.539 1058 1058 I update_engine: 561 ...
10-23 13:03:38.539 1058 1058 I update_engine:
10-23 13:03:38.539 1058 1058 I update_engine:
10-23 13:03:38.547 11610 11725 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
10-23 13:03:38.550 1058 1058 I update_engine: [1023/130338.550845:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
10-23 13:03:38.551 1058 1058 I update_engine: [1023/130338.551095:INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
10-23 13:03:38.551 1058 1058 I update_engine: [1023/130338.551198:INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (vendor) on device /dev/block/dm-1
10-23 13:03:38.583 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x211, isSetupWizard=false.
10-23 13:03:38.659 11610 11725 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:03:39.978 1058 1058 I update_engine: [1023/130339.977998:INFO:filesystem_verifier_action.cc(237)] Hash of vendor: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4=
10-23 13:03:40.142 1058 1058 I update_engine: [1023/130340.142583:INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (boot) on device /dev/block/bootdevice/by-name/boot_b
10-23 13:03:40.335 1058 1058 I update_engine: [1023/130340.335050:INFO:filesystem_verifier_action.cc(237)] Hash of boot: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU=
10-23 13:03:40.360 1058 1058 I update_engine: [1023/130340.360231:INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (system) on device /dev/block/dm-4
10-23 13:03:42.515 1058 1058 I update_engine: [1023/130342.515160:INFO:filesystem_verifier_action.cc(237)] Hash of system: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q=
10-23 13:03:42.755 1058 1058 I update_engine: [1023/130342.755483:INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (vbmeta) on device /dev/block/bootdevice/by-name/vbmeta_b
10-23 13:03:42.757 1058 1058 I update_engine: [1023/130342.757858:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg=
10-23 13:03:42.758 1058 1058 I update_engine: [1023/130342.758054:INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (dtbo) on device /dev/block/bootdevice/by-name/dtbo_b
10-23 13:03:42.784 1058 1058 I update_engine: [1023/130342.784619:INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c=
10-23 13:03:42.788 1058 1058 I update_engine: [1023/130342.788086:INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (product) on device /dev/block/dm-9
10-23 13:03:48.354 1058 1058 I update_engine: [1023/130348.354814:INFO:filesystem_verifier_action.cc(237)] Hash of product: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA=
10-23 13:03:48.927 1058 1058 I update_engine: [1023/130348.926983:INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (system_ext) on device /dev/block/dm-10
10-23 13:03:49.583 1058 1058 I update_engine: [1023/130349.583198:INFO:filesystem_verifier_action.cc(237)] Hash of system_ext: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM=
10-23 13:03:49.671 1058 1058 I update_engine: [1023/130349.671595:INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (vbmeta_system) on device /dev/block/bootdevice/by-name/vbmeta_system_b
10-23 13:03:49.674 1058 1058 I update_engine: [1023/130349.674193:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_system: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA=
10-23 13:03:49.674 1058 1058 I update_engine: [1023/130349.674387:INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (abl) on device /dev/block/bootdevice/by-name/abl_b
10-23 13:03:49.679 1058 1058 I update_engine: [1023/130349.679683:INFO:filesystem_verifier_action.cc(237)] Hash of abl: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA=
10-23 13:03:49.680 1058 1058 I update_engine: [1023/130349.680468:INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (aop) on device /dev/block/bootdevice/by-name/aop_b
10-23 13:03:49.682 1058 1058 I update_engine: [1023/130349.682602:INFO:filesystem_verifier_action.cc(237)] Hash of aop: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk=
10-23 13:03:49.683 1058 1058 I update_engine: [1023/130349.683041:INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (devcfg) on device /dev/block/bootdevice/by-name/devcfg_b
10-23 13:03:49.684 1058 1058 I update_engine: [1023/130349.684413:INFO:filesystem_verifier_action.cc(237)] Hash of devcfg: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE=
10-23 13:03:49.684 1058 1058 I update_engine: [1023/130349.684572:INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (hyp) on device /dev/block/bootdevice/by-name/hyp_b
10-23 13:03:49.687 1058 1058 I update_engine: [1023/130349.686985:INFO:filesystem_verifier_action.cc(237)] Hash of hyp: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w=
10-23 13:03:49.687 1058 1058 I update_engine: [1023/130349.687455:INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (keymaster) on device /dev/block/bootdevice/by-name/keymaster_b
10-23 13:03:49.690 1058 1058 I update_engine: [1023/130349.690054:INFO:filesystem_verifier_action.cc(237)] Hash of keymaster: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0=
10-23 13:03:49.690 1058 1058 I update_engine: [1023/130349.690328:INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (qupfw) on device /dev/block/bootdevice/by-name/qupfw_b
10-23 13:03:49.691 1058 1058 I update_engine: [1023/130349.691383:INFO:filesystem_verifier_action.cc(237)] Hash of qupfw: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w=
10-23 13:03:49.691 1058 1058 I update_engine: [1023/130349.691614:INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (tz) on device /dev/block/bootdevice/by-name/tz_b
10-23 13:03:49.700 1058 1058 I update_engine: [1023/130349.700007:INFO:filesystem_verifier_action.cc(237)] Hash of tz: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg=
10-23 13:03:49.701 1058 1058 I update_engine: [1023/130349.701043:INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (uefisecapp) on device /dev/block/bootdevice/by-name/uefisecapp_b
10-23 13:03:49.702 1058 1058 I update_engine: [1023/130349.702915:INFO:filesystem_verifier_action.cc(237)] Hash of uefisecapp: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo=
10-23 13:03:49.703 1058 1058 I update_engine: [1023/130349.703105:INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (xbl) on device /dev/block/bootdevice/by-name/xbl_b
10-23 13:03:49.714 1058 1058 I update_engine: [1023/130349.714638:INFO:filesystem_verifier_action.cc(237)] Hash of xbl: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE=
10-23 13:03:49.716 1058 1058 I update_engine: [1023/130349.716248:INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (xbl_config) on device /dev/block/bootdevice/by-name/xbl_config_b
10-23 13:03:49.717 1058 1058 I update_engine: [1023/130349.717937:INFO:filesystem_verifier_action.cc(237)] Hash of xbl_config: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY=
10-23 13:03:49.718 1058 1058 I update_engine: [1023/130349.718080:INFO:filesystem_verifier_action.cc(117)] Hashing partition 18 (modem) on device /dev/block/bootdevice/by-name/modem_b
10-23 13:03:49.941 1058 1058 I update_engine: [1023/130349.941612:INFO:filesystem_verifier_action.cc(237)] Hash of modem: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE=
10-23 13:03:49.973 1058 1058 I update_engine: [1023/130349.973521:INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
10-23 13:03:49.973 1058 1058 I update_engine: [1023/130349.973711:INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
10-23 13:03:49.977 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
10-23 13:03:49.978 1058 1058 I update_engine: [1023/130349.978474: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:03:49.980 1058 1058 I update_engine: [1023/130349.978633:INFO:postinstall_runner_action.cc(179)] Format file for new bin/checkpoint_gc is: data
10-23 13:03:50.039 1058 1058 I update_engine: [1023/130350.039154:INFO:subprocess.cc(157)] Subprocess output:
10-23 13:03:50.039 1058 1058 I update_engine: realpath: /dev/block/dm-8: Permission denied
10-23 13:03:50.039 1058 1058 I update_engine:
10-23 13:03:50.045 1058 1058 I update_engine: [1023/130350.045233: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:03:50.047 1058 1058 I update_engine: [1023/130350.045455:INFO:postinstall_runner_action.cc(179)] Format file for new system/bin/otapreopt_script is: data
10-23 13:03:50.071 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:03:50.074 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:03:57.068 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.510).
10-23 13:03:57.097 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.510000.
10-23 13:03:57.164 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:02.750 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.520).
10-23 13:04:02.784 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.520000.
10-23 13:04:02.857 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:09.825 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.530).
10-23 13:04:09.863 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.530000.
10-23 13:04:09.944 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:15.536 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.540).
10-23 13:04:15.578 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.540000.
10-23 13:04:15.663 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:21.057 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.550).
10-23 13:04:21.088 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.550000.
10-23 13:04:21.148 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:28.152 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.560).
10-23 13:04:28.188 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.560000.
10-23 13:04:28.256 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:28.875 11610 17927 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:04:28.887 11610 22875 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:04:33.747 11610 11745 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.570).
10-23 13:04:33.780 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.570000.
10-23 13:04:33.853 11610 11745 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:43.668 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.585).
10-23 13:04:43.703 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.585000.
10-23 13:04:43.783 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:49.337 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.595).
10-23 13:04:49.377 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.595000.
10-23 13:04:49.452 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:04:54.959 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.605).
10-23 13:04:54.996 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.605000.
10-23 13:04:55.071 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:02.190 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.615).
10-23 13:05:02.232 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.615000.
10-23 13:05:02.312 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:07.824 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.625).
10-23 13:05:07.859 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.625000.
10-23 13:05:07.947 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:14.898 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.635).
10-23 13:05:14.937 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.635000.
10-23 13:05:15.024 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:20.621 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.645).
10-23 13:05:20.658 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.645000.
10-23 13:05:20.754 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:26.303 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.655).
10-23 13:05:26.341 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.655000.
10-23 13:05:26.432 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:28.862 11610 17927 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:05:28.872 11610 23693 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:05:33.375 11610 11725 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.665).
10-23 13:05:33.414 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.665000.
10-23 13:05:33.509 11610 11725 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:39.045 11610 11725 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.675).
10-23 13:05:39.078 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.675000.
10-23 13:05:39.163 11610 11725 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:44.794 11610 11725 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.685).
10-23 13:05:44.829 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.685000.
10-23 13:05:44.911 11610 11725 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:05:52.020 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.695).
10-23 13:05:52.059 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.695000.
10-23 13:05:52.143 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:00.499 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.710).
10-23 13:06:00.534 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.710000.
10-23 13:06:00.596 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:07.655 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.720).
10-23 13:06:07.694 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.720000.
10-23 13:06:07.769 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:12.015 11610 17927 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:06:12.030 11610 24273 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:06:13.377 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.730).
10-23 13:06:13.409 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.730000.
10-23 13:06:13.483 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:20.480 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.740).
10-23 13:06:20.512 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.740000.
10-23 13:06:20.580 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:26.150 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.750).
10-23 13:06:26.181 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.750000.
10-23 13:06:26.252 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:28.864 11610 17927 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:06:28.875 11610 24513 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:06:31.864 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.760).
10-23 13:06:31.901 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.760000.
10-23 13:06:31.980 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:39.019 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.770).
10-23 13:06:39.051 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.770000.
10-23 13:06:39.116 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:44.704 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.780).
10-23 13:06:44.736 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.780000.
10-23 13:06:44.810 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:50.341 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.790).
10-23 13:06:50.378 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.790000.
10-23 13:06:50.462 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:06:57.478 11610 12355 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.800).
10-23 13:06:57.515 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.800000.
10-23 13:06:57.593 11610 12355 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:03.204 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.810).
10-23 13:07:03.237 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.810000.
10-23 13:07:03.314 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:10.224 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.820).
10-23 13:07:10.259 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.820000.
10-23 13:07:10.330 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:15.978 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.830).
10-23 13:07:16.014 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.830000.
10-23 13:07:16.096 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:21.692 11610 12143 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.840).
10-23 13:07:21.727 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.840000.
10-23 13:07:21.810 11610 12143 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:28.867 11610 17927 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:07:28.889 11610 25421 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:07:31.562 11610 12143 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.855).
10-23 13:07:31.597 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.855000.
10-23 13:07:31.669 11610 12143 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:37.172 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.865).
10-23 13:07:37.205 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.865000.
10-23 13:07:37.276 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:44.238 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.875).
10-23 13:07:44.274 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.875000.
10-23 13:07:44.360 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:49.902 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.885).
10-23 13:07:49.941 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.885000.
10-23 13:07:50.019 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:07:55.535 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.895).
10-23 13:07:55.571 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.895000.
10-23 13:07:55.652 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:02.502 11610 12144 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.905).
10-23 13:08:02.535 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.905000.
10-23 13:08:02.599 11610 12144 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:08.179 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.915).
10-23 13:08:08.216 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.915000.
10-23 13:08:08.295 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:15.147 11610 26049 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:08:15.256 11610 12144 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.925).
10-23 13:08:15.353 11610 12144 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:21.019 11610 12144 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.935).
10-23 13:08:21.122 11610 12144 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:26.760 11610 12144 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.945).
10-23 13:08:26.874 11610 12144 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:28.874 11610 11950 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
10-23 13:08:28.890 11610 26269 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
10-23 13:08:33.842 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.955).
10-23 13:08:33.943 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:39.495 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.965).
10-23 13:08:39.587 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:49.419 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.980).
10-23 13:08:49.527 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:08:55.072 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.990).
10-23 13:08:55.167 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:09:00.625 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 1.000).
10-23 13:09:00.718 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:09:02.036 11610 12272 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 1.000).
10-23 13:09:02.129 1058 1058 I update_engine: [1023/130902.129236:INFO:subprocess.cc(157)] Subprocess output:
10-23 13:09:02.129 1058 1058 I update_engine: Complete or error.
10-23 13:09:02.129 1058 1058 I update_engine:
10-23 13:09:02.134 1058 1058 I update_engine: [1023/130902.133981:INFO:postinstall_runner_action.cc(364)] All post-install commands succeeded
10-23 13:09:02.134 1058 1058 I update_engine: [1023/130902.134639:INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kUpdatedButNotActive
10-23 13:09:02.134 1058 1058 I update_engine: [1023/130902.134704:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 13:09:02.134 1058 1058 I update_engine: [1023/130902.134789:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, system_ext_b, vendor_b] from device mapper
10-23 13:09:02.141 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:09:02.142 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:09:02.142 11610 12144 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
10-23 13:09:02.353 1058 1058 I update_engine: [1023/130902.353576:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 13:09:02.775 1058 1058 I update_engine: [1023/130902.775435:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 13:09:03.197 1058 1058 I update_engine: [1023/130903.197033:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_ext_b from device mapper.
10-23 13:09:03.663 1058 1058 I update_engine: [1023/130903.663694:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 13:09:03.674 1058 1058 I update_engine: [1023/130903.674657:INFO:metrics_reporter_android.cc(29)] uploading 2 to histogram for metric ota_update_engine_attempt_number
10-23 13:09:03.675 1058 1058 I update_engine: [1023/130903.675262:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 13:09:03.675 1058 1058 I update_engine: [1023/130903.675703:INFO:metrics_reporter_android.cc(29)] uploading 5 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 13:09:03.676 1058 1058 I update_engine: [1023/130903.676370:INFO:metrics_reporter_android.cc(29)] uploading 5 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 13:09:03.677 1058 1058 I update_engine: [1023/130903.677101:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 13:09:03.677 1058 1058 I update_engine: [1023/130903.677881:INFO:metrics_reporter_android.cc(29)] uploading 12 to histogram for metric ota_update_engine_attempt_result
10-23 13:09:03.678 1058 1058 I update_engine: [1023/130903.678463:INFO:metrics_reporter_android.cc(29)] uploading 52 to histogram for metric ota_update_engine_attempt_error_code
10-23 13:09:03.678 11610 12144 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
10-23 13:09:03.680 1058 1058 I update_engine: [1023/130903.680694:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 13:09:03.827 11610 12144 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 13:09:03.869 11610 12272 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
10-23 13:09:03.916 11610 22314 I SystemUpdate: [Execution,SynchronizedUpdateEngine] stop()
10-23 13:09:03.917 11610 22314 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 329.10 seconds.
10-23 13:09:03.937 11610 22314 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:09:04.099 11610 22314 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.18 seconds.
10-23 13:09:28.337 11610 26786 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:09:28.343 11610 26787 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:09:28.368 11610 26787 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:09:28.404 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:09:28.498 11610 26786 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:09:28.539 11610 26787 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.19 seconds.
10-23 13:09:28.547 11610 26786 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:09:28.585 11610 26786 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.04 seconds.
10-23 13:09:28.768 11610 26786 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:09:28.774 11610 26787 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:09:28.813 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:09:28.832 11610 26787 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.06 seconds.
10-23 13:10:57.351 11610 26873 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:10:57.359 11610 26874 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:10:57.381 11610 26874 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:10:57.514 11610 26874 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.15 seconds.
10-23 13:17:34.095 11610 27056 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
10-23 13:17:34.103 11610 27058 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:17:34.147 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x110, isSetupWizard=false.
10-23 13:17:34.147 11610 27058 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
10-23 13:17:34.272 11610 27058 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.17 seconds.
10-23 13:17:38.037 11610 27058 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 105.
10-23 13:17:38.044 11610 27060 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:17:38.072 11610 27060 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x310, -1.000).
10-23 13:17:38.108 12197 12197 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x310, isSetupWizard=false.
10-23 13:17:38.232 11610 27060 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:17:38.232 11610 27060 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
10-23 13:17:38.238 1058 1058 I update_engine: [1023/131738.238437:INFO:update_attempter_android.cc(257)] Using this install plan:
10-23 13:17:38.238 1058 1058 I update_engine: [1023/131738.238788: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:17:38.239 1058 1058 I update_engine: [1023/131738.239295:INFO:metrics_utils.cc(357)] Payload Attempt Number = 3
10-23 13:17:38.239 1058 1058 I update_engine: [1023/131738.239707:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:34:14 GMT
10-23 13:17:38.239 1058 1058 I update_engine: [1023/131738.239967:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 0:34:14 GMT
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240022:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240255:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240318:INFO:update_boot_flags_action.cc(36)] Already updated boot flags. Skipping.
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240360:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240398:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240442:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240477:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240523: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:17:38.240 1058 1058 I update_engine: [1023/131738.240628:INFO:prefs.cc(122)] update-state-payload-index not present in /data/misc/update_engine/prefs
10-23 13:17:38.240 1058 1058 I update_engine: [1023/131738.240669:INFO:download_action.cc(199)] Marking new slot as unbootable
10-23 13:17:38.267 1058 1058 I update_engine: [1023/131738.267818:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
10-23 13:17:38.268 1058 1058 I update_engine: [1023/131738.267992:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 0+105160
10-23 13:17:38.270 1058 1058 I update_engine: [1023/131738.270795:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/1576938951 bytes downloaded (0%), overall progress 0%
10-23 13:17:38.274 1058 1058 I update_engine: [1023/131738.274141:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
10-23 13:17:38.274 1058 1058 I update_engine: [1023/131738.274422:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:17:38.274 1058 1058 I update_engine: [1023/131738.274863:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:17:38.276 1058 1058 I update_engine: [1023/131738.276231:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:17:38.276 1058 1058 I update_engine: [1023/131738.276331:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
10-23 13:17:38.283 1058 1058 I update_engine: [1023/131738.283583:INFO:delta_performer.cc(1643)] Detected a 'full' payload.
10-23 13:17:38.314 1058 1058 I update_engine: [1023/131738.314910:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
10-23 13:17:38.312 1058 1058 W update_engine: type=1400 audit(0.0:477): 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:17:38.317 1058 1058 I update_engine: [1023/131738.317584:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.376 1058 1058 I update_engine: [1023/131738.376697: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:17:38.376 1058 1058 W update_engine: type=1400 audit(0.0:478): 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:17:38.379 1058 1058 I update_engine: [1023/131738.379307:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.379 1058 1058 I update_engine: [1023/131738.379862:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
10-23 13:17:38.380 1058 1058 I update_engine: [1023/131738.380457:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.376 1058 1058 W update_engine: type=1400 audit(0.0:479): 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:17:38.382 1058 1058 I update_engine: [1023/131738.382489:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.444 1058 1058 I update_engine: [1023/131738.444093: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:17:38.442 1058 1058 W update_engine: type=1400 audit(0.0:480): 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:17:38.448 1058 1058 I update_engine: [1023/131738.448270:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.449 1058 1058 I update_engine: [1023/131738.448959:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
10-23 13:17:38.449 1058 1058 I update_engine: [1023/131738.449891:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.446 1058 1058 W update_engine: type=1400 audit(0.0:481): 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:17:38.452 1058 1058 I update_engine: [1023/131738.452883:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.453 1058 1058 I update_engine: [1023/131738.453733:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
10-23 13:17:38.454 1058 1058 I update_engine: [1023/131738.454512:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.452 1058 1058 W update_engine: type=1400 audit(0.0:482): 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:17:38.457 1058 1058 I update_engine: [1023/131738.457092:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.519 1058 1058 I update_engine: [1023/131738.519189: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:17:38.516 1058 1058 W update_engine: type=1400 audit(0.0:483): 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:17:38.522 1058 1058 I update_engine: [1023/131738.522125:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.583 1058 1058 I update_engine: [1023/131738.583420: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:17:38.586 1058 1058 I update_engine: [1023/131738.586726:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.587 1058 1058 I update_engine: [1023/131738.587266:INFO:boot_control_android.cc(140)] vbmeta_system_b is not in super partition metadata.
10-23 13:17:38.587 1058 1058 I update_engine: [1023/131738.587965:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.590 1058 1058 I update_engine: [1023/131738.590173:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.590 1058 1058 I update_engine: [1023/131738.590534:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
10-23 13:17:38.591 1058 1058 I update_engine: [1023/131738.591099:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.594 1058 1058 I update_engine: [1023/131738.594147:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.594 1058 1058 I update_engine: [1023/131738.594585:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
10-23 13:17:38.595 1058 1058 I update_engine: [1023/131738.595287:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.596 1058 1058 I update_engine: [1023/131738.596873:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.597 1058 1058 I update_engine: [1023/131738.597268:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
10-23 13:17:38.597 1058 1058 I update_engine: [1023/131738.597887:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.599 1058 1058 I update_engine: [1023/131738.599918:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.600 1058 1058 I update_engine: [1023/131738.600636:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
10-23 13:17:38.601 1058 1058 I update_engine: [1023/131738.601639:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.604 1058 1058 I update_engine: [1023/131738.603967:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.604 1058 1058 I update_engine: [1023/131738.604430:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
10-23 13:17:38.605 1058 1058 I update_engine: [1023/131738.605103:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.607 1058 1058 I update_engine: [1023/131738.606978:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.607 1058 1058 I update_engine: [1023/131738.607454:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
10-23 13:17:38.608 1058 1058 I update_engine: [1023/131738.608162:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.610 1058 1058 I update_engine: [1023/131738.610234:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.610 1058 1058 I update_engine: [1023/131738.610567:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
10-23 13:17:38.611 1058 1058 I update_engine: [1023/131738.611174:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.613 1058 1058 I update_engine: [1023/131738.613122:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.613 1058 1058 I update_engine: [1023/131738.613589:INFO:boot_control_android.cc(140)] uefisecapp_b is not in super partition metadata.
10-23 13:17:38.614 1058 1058 I update_engine: [1023/131738.614352:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.616 1058 1058 I update_engine: [1023/131738.616654:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.617 1058 1058 I update_engine: [1023/131738.617235:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
10-23 13:17:38.618 1058 1058 I update_engine: [1023/131738.618170:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.620 1058 1058 I update_engine: [1023/131738.620580:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.621 1058 1058 I update_engine: [1023/131738.621005:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
10-23 13:17:38.621 1058 1058 I update_engine: [1023/131738.621627:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.623 1058 1058 I update_engine: [1023/131738.623577:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/super
10-23 13:17:38.624 1058 1058 I update_engine: [1023/131738.624054:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
10-23 13:17:38.624 1058 1058 I update_engine: [1023/131738.624650:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/super
10-23 13:17:38.624 1058 1058 I update_engine: [1023/131738.624898:INFO:delta_performer.cc(450)] PartitionInfo new vendor sha256: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4= size: 550678528
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.624977:INFO:delta_performer.cc(450)] PartitionInfo new boot sha256: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU= size: 67108864
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625033:INFO:delta_performer.cc(450)] PartitionInfo new system sha256: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q= size: 820408320
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625085:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta sha256: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg= size: 8192
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625135:INFO:delta_performer.cc(450)] PartitionInfo new dtbo sha256: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c= size: 8388608
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625186:INFO:delta_performer.cc(450)] PartitionInfo new product sha256: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA= size: 2044289024
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625239:INFO:delta_performer.cc(450)] PartitionInfo new system_ext sha256: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM= size: 231182336
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625288:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta_system sha256: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA= size: 4096
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625338:INFO:delta_performer.cc(450)] PartitionInfo new abl sha256: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA= size: 1048576
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625388:INFO:delta_performer.cc(450)] PartitionInfo new aop sha256: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk= size: 167936
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625439:INFO:delta_performer.cc(450)] PartitionInfo new devcfg sha256: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE= size: 45056
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625554:INFO:delta_performer.cc(450)] PartitionInfo new hyp sha256: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w= size: 397312
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625608:INFO:delta_performer.cc(450)] PartitionInfo new keymaster sha256: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0= size: 241664
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625659:INFO:delta_performer.cc(450)] PartitionInfo new qupfw sha256: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w= size: 53248
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625712:INFO:delta_performer.cc(450)] PartitionInfo new tz sha256: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg= size: 2060288
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625763:INFO:delta_performer.cc(450)] PartitionInfo new uefisecapp sha256: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo= size: 126976
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625812:INFO:delta_performer.cc(450)] PartitionInfo new xbl sha256: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE= size: 3371008
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625861:INFO:delta_performer.cc(450)] PartitionInfo new xbl_config sha256: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY= size: 90112
10-23 13:17:38.625 1058 1058 I update_engine: [1023/131738.625910:INFO:delta_performer.cc(450)] PartitionInfo new modem sha256: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE= size: 74108928
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629083:INFO:delta_performer.cc(208)] Resuming after 1826/1826 operations (100%), 1576938951/1576938951 bytes downloaded (100%), overall progress 100%
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629210:INFO:delta_performer.cc(654)] Starting to apply update payload operations
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629255:INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629318:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629360:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 200
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629396:INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629469:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
10-23 13:17:38.629 1058 1058 I update_engine: [1023/131738.629549:INFO:payload_verifier.cc(58)] signature blob size = 523
10-23 13:17:38.630 1058 1058 I update_engine: [1023/131738.630596:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
10-23 13:17:38.630 1058 1058 I update_engine: [1023/131738.630682:INFO:delta_performer.cc(1829)] Payload hash matches value in payload.
10-23 13:17:38.630 1058 1058 I update_engine: [1023/131738.630873:INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
10-23 13:17:38.630 1058 1058 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 1826 samples, mean = 126.6
10-23 13:17:38.630 1058 1058 I update_engine: 0 --O (21 = 1.2%)
10-23 13:17:38.630 1058 1058 I update_engine: 10 --------O (101 = 5.5%) {1.2%}
10-23 13:17:38.630 1058 1058 I update_engine: 18 ------O (77 = 4.2%) {6.7%}
10-23 13:17:38.630 1058 1058 I update_engine: 32 ---O (41 = 2.2%) {10.9%}
10-23 13:17:38.630 1058 1058 I update_engine: 57 ----------------------------O (355 = 19.4%) {13.1%}
10-23 13:17:38.630 1058 1058 I update_engine: 101 ------------------------------------------------------------------------O (923 = 50.5%) {32.6%}
10-23 13:17:38.630 1058 1058 I update_engine: 179 ----------------------O (288 = 15.8%) {83.1%}
10-23 13:17:38.630 1058 1058 I update_engine: 317 --O (20 = 1.1%) {98.9%}
10-23 13:17:38.630 1058 1058 I update_engine: 561 ...
10-23 13:17:38.630 1058 1058 I update_engine:
10-23 13:17:38.630 1058 1058 I update_engine:
10-23 13:17:38.639 1058 1058 I update_engine: [1023/131738.639718:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
10-23 13:17:38.639 1058 1058 I update_engine: [1023/131738.639911:INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
10-23 13:17:38.640 1058 1058 I update_engine: [1023/131738.639985:INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (vendor) on device /dev/block/dm-1
10-23 13:17:40.128 1058 1058 I update_engine: [1023/131740.128693:INFO:filesystem_verifier_action.cc(237)] Hash of vendor: gL1FIlaFvgnSMckvQZHJmHmqebofugfbdNjCVSFRkP4=
10-23 13:17:40.312 1058 1058 I update_engine: [1023/131740.312381:INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (boot) on device /dev/block/bootdevice/by-name/boot_b
10-23 13:17:40.516 1058 1058 I update_engine: [1023/131740.516192:INFO:filesystem_verifier_action.cc(237)] Hash of boot: GbFZDiubLWRrds6qsbdUshkS96i11t+6YlqUHhrlZEU=
10-23 13:17:40.543 1058 1058 I update_engine: [1023/131740.543650:INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (system) on device /dev/block/dm-4
10-23 13:17:42.742 1058 1058 I update_engine: [1023/131742.742894:INFO:filesystem_verifier_action.cc(237)] Hash of system: KFuREjC15gGTHRznhs1T60Z0FhOF6TdML4q7w2A512Q=
10-23 13:17:42.990 1058 1058 I update_engine: [1023/131742.990706:INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (vbmeta) on device /dev/block/bootdevice/by-name/vbmeta_b
10-23 13:17:42.993 1058 1058 I update_engine: [1023/131742.993841:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: cRBgMzIm4t4b7MzNyTTXrh8KVu6FQuzbBHcvn1aEoRg=
10-23 13:17:42.994 1058 1058 I update_engine: [1023/131742.994055:INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (dtbo) on device /dev/block/bootdevice/by-name/dtbo_b
10-23 13:17:43.020 1058 1058 I update_engine: [1023/131743.020620:INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: K5rPMf2i3Sgi8tm8XqCUJZoMrT7+w0hNUduLOYZ6B0c=
10-23 13:17:43.023 1058 1058 I update_engine: [1023/131743.023647:INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (product) on device /dev/block/dm-9
10-23 13:17:48.420 1058 1058 I update_engine: [1023/131748.419958:INFO:filesystem_verifier_action.cc(237)] Hash of product: SZHs2zjvjiUJ28Iwf0vGtIYo6FhwVEnWmxrWodIUaPA=
10-23 13:17:48.984 1058 1058 I update_engine: [1023/131748.984174:INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (system_ext) on device /dev/block/dm-10
10-23 13:17:49.650 1058 1058 I update_engine: [1023/131749.650253:INFO:filesystem_verifier_action.cc(237)] Hash of system_ext: 8zVMYZbXawCO/rDpg5mbr+MR2M6lJeyDETd2EsA4BFM=
10-23 13:17:49.727 1058 1058 I update_engine: [1023/131749.727230:INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (vbmeta_system) on device /dev/block/bootdevice/by-name/vbmeta_system_b
10-23 13:17:49.732 1058 1058 I update_engine: [1023/131749.732637:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_system: 8vlxfhuGzRZopv05OeKY/vLr+aKmQOOvw7qqeoQWzAA=
10-23 13:17:49.732 1058 1058 I update_engine: [1023/131749.732862:INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (abl) on device /dev/block/bootdevice/by-name/abl_b
10-23 13:17:49.738 1058 1058 I update_engine: [1023/131749.738186:INFO:filesystem_verifier_action.cc(237)] Hash of abl: eNU69cw4jLuWHVc09Dltkra/Kfut3q5cEYxi/weP0KA=
10-23 13:17:49.738 1058 1058 I update_engine: [1023/131749.738856:INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (aop) on device /dev/block/bootdevice/by-name/aop_b
10-23 13:17:49.740 1058 1058 I update_engine: [1023/131749.740774:INFO:filesystem_verifier_action.cc(237)] Hash of aop: b+D3X9dYoT/xWURMXM7GxadGOIGIQDCVT19i/XJLgCk=
10-23 13:17:49.741 1058 1058 I update_engine: [1023/131749.741095:INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (devcfg) on device /dev/block/bootdevice/by-name/devcfg_b
10-23 13:17:49.742 1058 1058 I update_engine: [1023/131749.742399:INFO:filesystem_verifier_action.cc(237)] Hash of devcfg: nO7Wjbs9T3KMROdVDpnYx6KnmHQlU49AEWpR/u39DtE=
10-23 13:17:49.742 1058 1058 I update_engine: [1023/131749.742553:INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (hyp) on device /dev/block/bootdevice/by-name/hyp_b
10-23 13:17:49.744 1058 1058 I update_engine: [1023/131749.744760:INFO:filesystem_verifier_action.cc(237)] Hash of hyp: vFIs70jY6DC30Yp3Tsf/wT5Zg6ipk2yW/lVDN4L399w=
10-23 13:17:49.745 1058 1058 I update_engine: [1023/131749.745058:INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (keymaster) on device /dev/block/bootdevice/by-name/keymaster_b
10-23 13:17:49.746 1058 1058 I update_engine: [1023/131749.746906:INFO:filesystem_verifier_action.cc(237)] Hash of keymaster: gCl03SZEY5qACY8eXlCzj2tLVNvVRXn1DO/m925dmR0=
10-23 13:17:49.747 1058 1058 I update_engine: [1023/131749.747167:INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (qupfw) on device /dev/block/bootdevice/by-name/qupfw_b
10-23 13:17:49.748 1058 1058 I update_engine: [1023/131749.748138:INFO:filesystem_verifier_action.cc(237)] Hash of qupfw: /2vtz4Rzn4jlqylhniO02OGWmO62ZHnHijvX1pe7V1w=
10-23 13:17:49.748 1058 1058 I update_engine: [1023/131749.748259:INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (tz) on device /dev/block/bootdevice/by-name/tz_b
10-23 13:17:49.755 1058 1058 I update_engine: [1023/131749.755204:INFO:filesystem_verifier_action.cc(237)] Hash of tz: zT5+hXSlF41ygmJx6fReBg0A5tuHsPS2LSjL4DB4BWg=
10-23 13:17:49.756 1058 1058 I update_engine: [1023/131749.756090:INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (uefisecapp) on device /dev/block/bootdevice/by-name/uefisecapp_b
10-23 13:17:49.757 1058 1058 I update_engine: [1023/131749.757795:INFO:filesystem_verifier_action.cc(237)] Hash of uefisecapp: 3+mbLacKzJ3dYWgYT1SFnxL5Hz2qpis3HqGIBWs2ygo=
10-23 13:17:49.757 1058 1058 I update_engine: [1023/131749.757943:INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (xbl) on device /dev/block/bootdevice/by-name/xbl_b
10-23 13:17:49.769 1058 1058 I update_engine: [1023/131749.769108:INFO:filesystem_verifier_action.cc(237)] Hash of xbl: T/3gnjL13PoFQqFhC752yq3kRrX99J71NFTbMCpW1bE=
10-23 13:17:49.770 1058 1058 I update_engine: [1023/131749.770649:INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (xbl_config) on device /dev/block/bootdevice/by-name/xbl_config_b
10-23 13:17:49.772 1058 1058 I update_engine: [1023/131749.772290:INFO:filesystem_verifier_action.cc(237)] Hash of xbl_config: bTQYcJ00/h07XagoJYXL4/KZghh1C42IYxTDajOBWhY=
10-23 13:17:49.772 1058 1058 I update_engine: [1023/131749.772439:INFO:filesystem_verifier_action.cc(117)] Hashing partition 18 (modem) on device /dev/block/bootdevice/by-name/modem_b
10-23 13:17:49.972 1058 1058 I update_engine: [1023/131749.972103:INFO:filesystem_verifier_action.cc(237)] Hash of modem: I34Jqd+1RRJfEayfd/IfcXuwl6+lI5F55PhXJWSFLcE=
10-23 13:17:49.998 1058 1058 I update_engine: [1023/131749.998300:INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
10-23 13:17:49.998 1058 1058 I update_engine: [1023/131749.998429:INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
10-23 13:17:49.998 1058 1058 I update_engine: [1023/131749.998586:INFO:postinstall_runner_action.cc(87)] Skipping post-install according to install plan.
10-23 13:17:50.019 1058 1058 I update_engine: [1023/131750.019495:INFO:postinstall_runner_action.cc(364)] All post-install commands succeeded
10-23 13:17:50.019 1058 1058 I update_engine: [1023/131750.019923:INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kSuccess
10-23 13:17:50.019 1058 1058 I update_engine: [1023/131750.019982:INFO:update_attempter_android.cc(454)] Processing Done.
10-23 13:17:50.021 1058 1058 I update_engine: [1023/131750.021083:INFO:update_attempter_android.cc(462)] Update successfully applied, waiting to reboot.
10-23 13:17:50.021 1058 1058 I update_engine: [1023/131750.021168:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, system_ext_b, vendor_b] from device mapper
10-23 13:17:50.236 1058 1058 I update_engine: [1023/131750.236292:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
10-23 13:17:50.693 1058 1058 I update_engine: [1023/131750.693432:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
10-23 13:17:51.112 1058 1058 I update_engine: [1023/131751.112083:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_ext_b from device mapper.
10-23 13:17:51.526 1058 1058 I update_engine: [1023/131751.526718:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
10-23 13:17:51.530 1058 1058 I update_engine: [1023/131751.530763:INFO:metrics_reporter_android.cc(29)] uploading 3 to histogram for metric ota_update_engine_attempt_number
10-23 13:17:51.531 1058 1058 I update_engine: [1023/131751.531061:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
10-23 13:17:51.531 1058 1058 I update_engine: [1023/131751.531253:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
10-23 13:17:51.531 1058 1058 I update_engine: [1023/131751.531529:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
10-23 13:17:51.531 1058 1058 I update_engine: [1023/131751.531720:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_attempt_payload_size_mib
10-23 13:17:51.531 1058 1058 I update_engine: [1023/131751.531886:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_result
10-23 13:17:51.532 1058 1058 I update_engine: [1023/131751.532059:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_error_code
10-23 13:17:51.533 1058 1058 I update_engine: [1023/131751.533671:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
10-23 13:17:51.535 1058 1058 I update_engine: [1023/131751.535519:INFO:metrics_reporter_android.cc(29)] uploading 3 to histogram for metric ota_update_engine_successful_update_attempt_count
10-23 13:17:51.535 1058 1058 I update_engine: [1023/131751.535651:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_payload_type
10-23 13:17:51.535 1058 1058 I update_engine: [1023/131751.535727:INFO:metrics_reporter_android.cc(29)] uploading 1503 to histogram for metric ota_update_engine_successful_update_payload_size_mib
10-23 13:17:51.535 1058 1058 I update_engine: [1023/131751.535797:INFO:metrics_reporter_android.cc(29)] uploading 1504 to histogram for metric ota_update_engine_successful_update_total_bytes_downloaded_mib
10-23 13:17:51.535 1058 1058 I update_engine: [1023/131751.535867:INFO:metrics_reporter_android.cc(29)] uploading 1499661 to histogram for metric ota_update_engine_successful_update_download_overhead_percentage
10-23 13:17:51.535 1058 1058 I update_engine: [1023/131751.535937: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:17:51.536 1058 1058 I update_engine: [1023/131751.536005:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_successful_update_reboot_count
10-23 13:17:51.537 1058 1058 I update_engine: [1023/131751.537094:INFO:metrics_utils.cc(366)] Updated Marker = 1/1/1970 0:34:27 GMT
10-23 13:17:51.551 11610 27060 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 13.51 seconds.
10-23 13:17:51.806 11610 27078 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
--------- beginning of system
--------- beginning of main
10-23 13:19:51.978 4375 7139 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:19:52.023 4375 7139 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: LogStatus.
10-23 13:19:52.071 4375 7942 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 1.
10-23 13:19:52.079 4375 7887 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:19:52.107 4375 7887 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x005, -1.000).
10-23 13:19:52.376 4375 7887 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.29 seconds.
10-23 13:19:52.382 4375 7887 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:20:17.104 4375 8179 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:20:17.209 4375 8179 I SystemUpdate: [Control,InstallationControl] Update URL changed from "https://ota.googlezip.net/packages/ota-api/google_sunfish_sunfish/4e536e3e15bfab486e26dbed8170f2e0ad164456.zip" to "".
10-23 13:20:17.449 4375 9524 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:20:17.458 4375 9517 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:20:17.460 4375 9524 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 102.
10-23 13:20:17.460 4375 9517 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:20:17.465 4375 9524 I SystemUpdate: [Execution,ExecutionManager] Finishing the current execution.
10-23 13:20:17.490 4375 8179 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: AutomaticUpdateFlagChanged.
10-23 13:20:17.497 4375 8179 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: AutomaticUpdateFlagChanged.
10-23 13:20:17.518 4375 9524 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
10-23 13:20:17.559 4375 9517 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:20:17.621 4375 9517 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x000, -1.000).
10-23 13:20:17.841 4375 9517 I SystemUpdate: [Execution,ExecutionManager] Action pending executed for 0.27 seconds.
10-23 13:20:17.847 4375 9517 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:20:18.842 4375 9524 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:20:18.861 4375 9516 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:20:18.882 4375 8179 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:20:18.891 4375 8179 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
10-23 13:20:19.480 4375 9595 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:20:19.516 4375 8179 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
10-23 13:20:19.530 4375 9524 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:20:19.531 4375 9524 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment