Created
October 23, 2020 08:10
-
-
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
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
--------- 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 |
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
--------- 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@[email protected] (120408-256199907):68) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awuv.a(:com.google.android.gms@[email protected] (120408-256199907):6) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at awtr.c(:com.google.android.gms@[email protected] (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@[email protected] (120408-256199907):14) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@[email protected] (120408-256199907):2) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at qgd.onHandleIntent(:com.google.android.gms@[email protected] (120408-256199907):4) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at eeh.run(:com.google.android.gms@[email protected] (120408-256199907):10) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at eec.run(:com.google.android.gms@[email protected] (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@[email protected] (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@[email protected] (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@[email protected] (120408-256199907):37) | |
10-23 12:45:51.906 3183 5853 E SystemUpdate: at sko.run(:com.google.android.gms@[email protected] (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@[email protected] (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@[email protected] (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@[email protected] (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. |
This file contains hidden or bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
--------- 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