Skip to content

Instantly share code, notes, and snippets.

@nooriro
Created April 7, 2020 00:05
Show Gist options
  • Save nooriro/aa03f3e2cc427319e3a070021cb3cfe4 to your computer and use it in GitHub Desktop.
Save nooriro/aa03f3e2cc427319e3a070021cb3cfe4 to your computer and use it in GitHub Desktop.
crosshatch update log: QQ2A.200305.002 to QQ2A.200405.005 (succeeded log) / OTA zip URL is https://ota.googlezip.net/packages/ota-api/google_crosshatch_crosshatch/9e10c787db4a5bc29c77995893391ff0793ed2dc.zip
--------- beginning of system
--------- beginning of main
04-07 08:27:51.858 2790 2926 I SystemUpdate: [Api,CheckForConfigUpdateOperation] Checkin request finished with code Optional.of(21021).
04-07 08:27:51.862 7462 7666 I SystemUpdate: [Common,RequestConfigUpdateLoader] Config update succeeded.
04-07 08:28:16.113 2790 10117 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 }.
04-07 08:28:16.443 2790 10117 I SystemUpdate: [Control,InstallationControl] Update URL changed from "" to "https://ota.googlezip.net/packages/ota-api/google_crosshatch_crosshatch/9e10c787db4a5bc29c77995893391ff0793ed2dc.zip".
04-07 08:28:16.514 2790 9913 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 2.
04-07 08:28:16.516 2790 10112 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 }.
04-07 08:28:16.516 2790 9913 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 102.
04-07 08:28:16.517 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds.
04-07 08:28:16.518 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Finishing the current execution.
04-07 08:28:16.551 2790 10112 I SystemUpdate: [Execution,SynchronizedUpdateEngine] reset()
04-07 08:28:16.586 2790 10112 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 }.
04-07 08:28:16.598 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action pending executed for 0.01 seconds.
04-07 08:28:16.606 2790 10112 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x017, -1.000).
04-07 08:28:16.617 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x017, isSetupWizard=false.
04-07 08:28:31.697 2790 10112 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_header.bin with size 24.
04-07 08:28:33.881 2790 10112 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_metadata.bin with size 217772.
04-07 08:28:34.215 2790 10112 I SystemUpdate: [Execution,UpdateEngineDelegate] verifyPayloadMetadata()
04-07 08:28:34.215 1092 1092 I update_engine: [0407/082834.215432:INFO:binder_service_android.cc(145)] Received a request of verifying payload metadata in /data/ota_package/payload_metadata.bin.
04-07 08:28:34.220 1092 1092 I update_engine: [0407/082834.220121:INFO:payload_verifier.cc(58)] signature blob size = 264
04-07 08:28:34.221 1092 1092 I update_engine: [0407/082834.221083:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
04-07 08:28:34.221 1092 1092 I update_engine: [0407/082834.221168:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
04-07 08:28:34.248 1092 1092 I update_engine: type=1400 audit(0.0:750): avc: denied { search } for name="gsi" dev="sda20" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=1
04-07 08:28:34.257 1092 1092 I update_engine: [0407/082834.257591:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:34.258 1092 1092 I update_engine: [0407/082834.258441:INFO:boot_control_android.cc(140)] boot_a is not in super partition metadata.
04-07 08:28:34.259 1092 1092 I update_engine: [0407/082834.259108:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:34.403 1092 1092 I update_engine: [0407/082834.403287:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:34.403 1092 1092 I update_engine: [0407/082834.403717:INFO:boot_control_android.cc(163)] system_a is mapped on device mapper: /dev/block/dm-0
04-07 08:28:36.161 2790 10509 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:28:40.548 1092 1092 I update_engine: type=1400 audit(0.0:751): avc: denied { search } for name="gsi" dev="sda20" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=1
04-07 08:28:40.556 1092 1092 I update_engine: [0407/082840.556461:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:40.557 1092 1092 I update_engine: [0407/082840.557871:INFO:boot_control_android.cc(140)] vbmeta_a is not in super partition metadata.
04-07 08:28:40.558 1092 1092 I update_engine: [0407/082840.558494:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:40.565 1092 1092 I update_engine: [0407/082840.565856:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:40.566 1092 1092 I update_engine: [0407/082840.566214:INFO:boot_control_android.cc(140)] dtbo_a is not in super partition metadata.
04-07 08:28:40.566 1092 1092 I update_engine: [0407/082840.566753:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:40.585 1092 1092 I update_engine: [0407/082840.585653:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:40.586 1092 1092 I update_engine: [0407/082840.586922:INFO:boot_control_android.cc(163)] product_a is mapped on device mapper: /dev/block/dm-2
04-07 08:28:52.661 1092 1092 I update_engine: type=1400 audit(0.0:752): avc: denied { search } for name="gsi" dev="sda20" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=1
04-07 08:28:52.667 1092 1092 I update_engine: [0407/082852.666947:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:52.668 1092 1092 I update_engine: [0407/082852.668370:INFO:boot_control_android.cc(163)] vendor_a is mapped on device mapper: /dev/block/dm-1
04-07 08:28:55.667 1092 1092 I update_engine: [0407/082855.667040:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.667 1092 1092 I update_engine: [0407/082855.667613:INFO:boot_control_android.cc(140)] abl_a is not in super partition metadata.
04-07 08:28:55.668 1092 1092 I update_engine: [0407/082855.668147:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.679 1092 1092 I update_engine: [0407/082855.679155:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.680 1092 1092 I update_engine: [0407/082855.680405:INFO:boot_control_android.cc(140)] aop_a is not in super partition metadata.
04-07 08:28:55.680 1092 1092 I update_engine: [0407/082855.680946:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.692 1092 1092 I update_engine: [0407/082855.692150:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.693 1092 1092 I update_engine: [0407/082855.693246:INFO:boot_control_android.cc(140)] cmnlib_a is not in super partition metadata.
04-07 08:28:55.693 1092 1092 I update_engine: [0407/082855.693782:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.701 1092 1092 I update_engine: [0407/082855.701219:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.701 1092 1092 I update_engine: [0407/082855.701562:INFO:boot_control_android.cc(140)] cmnlib64_a is not in super partition metadata.
04-07 08:28:55.702 1092 1092 I update_engine: [0407/082855.702046:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.709 1092 1092 I update_engine: [0407/082855.709853:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.710 1092 1092 I update_engine: [0407/082855.710141:INFO:boot_control_android.cc(140)] devcfg_a is not in super partition metadata.
04-07 08:28:55.710 1092 1092 I update_engine: [0407/082855.710779:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.717 1092 1092 I update_engine: [0407/082855.717079:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.717 1092 1092 I update_engine: [0407/082855.717340:INFO:boot_control_android.cc(140)] hyp_a is not in super partition metadata.
04-07 08:28:55.717 1092 1092 I update_engine: [0407/082855.717955:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.725 1092 1092 I update_engine: [0407/082855.725829:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.726 1092 1092 I update_engine: [0407/082855.726128:INFO:boot_control_android.cc(140)] keymaster_a is not in super partition metadata.
04-07 08:28:55.726 1092 1092 I update_engine: [0407/082855.726655:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.738 1092 1092 I update_engine: [0407/082855.738319:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.738 1092 1092 I update_engine: [0407/082855.738717:INFO:boot_control_android.cc(140)] qupfw_a is not in super partition metadata.
04-07 08:28:55.739 1092 1092 I update_engine: [0407/082855.739168:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.745 1092 1092 I update_engine: [0407/082855.745170:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.745 1092 1092 I update_engine: [0407/082855.745469:INFO:boot_control_android.cc(140)] tz_a is not in super partition metadata.
04-07 08:28:55.745 1092 1092 I update_engine: [0407/082855.745930:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.792 1092 1092 I update_engine: [0407/082855.792429:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.793 1092 1092 I update_engine: [0407/082855.793946:INFO:boot_control_android.cc(140)] xbl_a is not in super partition metadata.
04-07 08:28:55.794 1092 1092 I update_engine: [0407/082855.794627:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.828 1092 1092 I update_engine: [0407/082855.828313:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.829 1092 1092 I update_engine: [0407/082855.829740:INFO:boot_control_android.cc(140)] xbl_config_a is not in super partition metadata.
04-07 08:28:55.830 1092 1092 I update_engine: [0407/082855.830227:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.836 1092 1092 I update_engine: [0407/082855.836207:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:28:55.837 1092 1092 I update_engine: [0407/082855.837231:INFO:boot_control_android.cc(140)] modem_a is not in super partition metadata.
04-07 08:28:55.837 1092 1092 I update_engine: [0407/082855.837800:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:29:08.255 2790 10112 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 729.
04-07 08:29:08.367 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action pre-download-validate executed for 51.77 seconds.
04-07 08:29:08.464 2790 10112 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
04-07 08:29:08.519 2790 10112 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
04-07 08:29:11.574 2790 10112 E SystemUpdate: [Network,NormalNetworkRequester] Failed to acquire the non-metered network.
04-07 08:29:11.574 2790 10112 E SystemUpdate: java.io.IOException: Timeout [3000] when acquiring the network.
04-07 08:29:11.574 2790 10112 E SystemUpdate: at avmr.a(:com.google.android.gms@200914037@20.09.14 (120400-300565878):14)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at avmr.b(:com.google.android.gms@200914037@20.09.14 (120400-300565878):8)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at avms.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):1)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at avlh.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at com.google.android.gms.update.execution.InstallationIntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):13)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):2)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at qsr.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at dua.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):5)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at dtz.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):7)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-07 08:29:11.574 2790 10112 E SystemUpdate: at java.lang.Thread.run(Thread.java:919)
04-07 08:29:11.575 2790 10112 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [12]...
04-07 08:29:11.584 2790 3795 I SystemUpdate: [Network,NetworkRequester] Network acquired.
04-07 08:29:11.589 2790 3790 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:29:11.617 2790 10666 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:29:11.638 2790 10112 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x80B, -1.000).
04-07 08:29:11.684 2790 10112 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: WifiConnected.
04-07 08:29:11.690 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 3.32 seconds.
04-07 08:29:11.699 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
04-07 08:29:11.705 2790 10112 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 }.
04-07 08:29:11.729 2790 10112 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
04-07 08:29:11.762 2790 10112 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
04-07 08:29:14.814 2790 10112 E SystemUpdate: [Network,NormalNetworkRequester] Failed to acquire the non-metered network.
04-07 08:29:14.814 2790 10112 E SystemUpdate: java.io.IOException: Timeout [3000] when acquiring the network.
04-07 08:29:14.814 2790 10112 E SystemUpdate: at avmr.a(:com.google.android.gms@200914037@20.09.14 (120400-300565878):14)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at avmr.b(:com.google.android.gms@200914037@20.09.14 (120400-300565878):8)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at avms.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):1)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at avlh.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at com.google.android.gms.update.execution.InstallationIntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):13)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):2)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at qsr.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at dua.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):5)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at dtz.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):7)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-07 08:29:14.814 2790 10112 E SystemUpdate: at java.lang.Thread.run(Thread.java:919)
04-07 08:29:14.814 2790 10112 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [12]...
04-07 08:29:14.823 2790 3795 I SystemUpdate: [Network,NetworkRequester] Network acquired.
04-07 08:29:14.829 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:29:14.857 2790 10666 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:29:14.877 2790 10112 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x80B, -1.000).
04-07 08:29:14.920 2790 10112 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: WifiConnected.
04-07 08:29:14.926 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 3.22 seconds.
04-07 08:29:14.935 2790 10112 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
04-07 08:29:58.826 2790 10756 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:29:58.831 2790 10752 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 }.
04-07 08:29:58.849 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x80B, isSetupWizard=false.
04-07 08:29:58.863 2790 10752 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
04-07 08:29:58.877 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x002, isSetupWizard=false.
04-07 08:29:58.907 2790 10752 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
04-07 08:30:01.911 2790 10752 E SystemUpdate: [Network,NormalNetworkRequester] Failed to acquire the non-metered network.
04-07 08:30:01.911 2790 10752 E SystemUpdate: java.io.IOException: Timeout [3000] when acquiring the network.
04-07 08:30:01.911 2790 10752 E SystemUpdate: at avmr.a(:com.google.android.gms@200914037@20.09.14 (120400-300565878):14)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at avmr.b(:com.google.android.gms@200914037@20.09.14 (120400-300565878):8)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at avms.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):1)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at avlh.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at com.google.android.gms.update.execution.InstallationIntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):13)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):2)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at qsr.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at dua.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):5)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at dtz.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):7)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-07 08:30:01.911 2790 10752 E SystemUpdate: at java.lang.Thread.run(Thread.java:919)
04-07 08:30:01.911 2790 10752 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [12]...
04-07 08:30:01.917 2790 3795 I SystemUpdate: [Network,NetworkRequester] Network acquired.
04-07 08:30:01.920 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:30:01.944 2790 10753 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:30:01.955 2790 10752 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x80B, -1.000).
04-07 08:30:01.967 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x80B, isSetupWizard=false.
04-07 08:30:01.997 2790 10752 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: WifiConnected.
04-07 08:30:02.003 2790 10752 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 3.17 seconds.
04-07 08:30:02.010 2790 10752 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.00 seconds.
04-07 08:30:16.594 2790 10830 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 107.
04-07 08:30:16.598 2790 10832 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 }.
04-07 08:30:16.611 2790 10832 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
04-07 08:30:16.617 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x002, isSetupWizard=false.
04-07 08:30:16.631 2790 10832 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
04-07 08:30:19.633 2790 10832 E SystemUpdate: [Network,NormalNetworkRequester] Failed to acquire the non-metered network.
04-07 08:30:19.633 2790 10832 E SystemUpdate: java.io.IOException: Timeout [3000] when acquiring the network.
04-07 08:30:19.633 2790 10832 E SystemUpdate: at avmr.a(:com.google.android.gms@200914037@20.09.14 (120400-300565878):14)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at avmr.b(:com.google.android.gms@200914037@20.09.14 (120400-300565878):8)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at avms.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):1)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at avlh.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at com.google.android.gms.update.execution.InstallationIntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):13)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):2)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at qsr.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at dua.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):5)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at dtz.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):7)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-07 08:30:19.633 2790 10832 E SystemUpdate: at java.lang.Thread.run(Thread.java:919)
04-07 08:30:19.633 2790 10832 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [12]...
04-07 08:30:19.636 2790 3795 I SystemUpdate: [Network,NetworkRequester] Network acquired.
04-07 08:30:19.638 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:30:19.664 2790 10830 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:30:19.672 2790 10832 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x002, -1.000).
04-07 08:30:22.303 2790 10832 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/payload_properties.txt with size 154.
04-07 08:30:25.185 2790 10832 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/care_map.pb with size 539.
04-07 08:30:27.744 2790 10832 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/compatibility.zip with size 5537.
04-07 08:30:30.100 2790 10832 I SystemUpdate: [Execution,PackageFiles] Aggressive allocating space for file /data/ota_package/metadata with size 729.
04-07 08:30:30.105 2790 10832 I SystemUpdate: [Execution,ExecutionManager] Action streaming-download executed for 13.51 seconds.
04-07 08:30:30.108 2790 10832 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x003, -1.000).
04-07 08:30:30.114 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x003, isSetupWizard=false.
04-07 08:30:30.161 2790 10832 I SystemUpdate: [Execution,ExecutionManager] Action streaming-process-package executed for 0.05 seconds.
04-07 08:30:30.181 2790 10832 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
04-07 08:30:30.186 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x413, isSetupWizard=false.
04-07 08:30:30.198 2790 10832 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
04-07 08:30:30.199 2790 10832 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.04 seconds.
04-07 08:30:30.204 2790 10832 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.00 seconds.
04-07 08:30:48.996 2790 10906 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:30:49.003 2790 10907 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 }.
04-07 08:30:49.028 2790 10907 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
04-07 08:30:49.067 2790 10907 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
04-07 08:30:49.070 2790 10907 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.07 seconds.
04-07 08:30:49.078 2790 10907 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
04-07 08:31:31.248 2790 10960 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:31:31.250 2790 10962 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 }.
04-07 08:31:31.262 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x413, isSetupWizard=false.
04-07 08:31:31.270 2790 10962 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x413, -1.000).
04-07 08:31:31.296 2790 10962 I SystemUpdate: [Control,ChimeraGcmTaskService] Scheduling task: DeviceIdle.
04-07 08:31:31.297 2790 10962 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 0.04 seconds.
04-07 08:31:31.304 2790 10962 I SystemUpdate: [Execution,ExecutionManager] Action fixed-delay-execution executed for 0.01 seconds.
04-07 08:31:35.833 2790 10962 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 109.
04-07 08:31:35.834 2790 10961 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 }.
04-07 08:31:35.850 2790 10961 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
04-07 08:31:35.856 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x111, isSetupWizard=false.
04-07 08:31:35.866 2790 10961 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [11, 12]...
04-07 08:31:38.868 2790 10961 E SystemUpdate: [Network,NormalNetworkRequester] Failed to acquire the non-metered network.
04-07 08:31:38.868 2790 10961 E SystemUpdate: java.io.IOException: Timeout [3000] when acquiring the network.
04-07 08:31:38.868 2790 10961 E SystemUpdate: at avmr.a(:com.google.android.gms@200914037@20.09.14 (120400-300565878):14)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at avmr.b(:com.google.android.gms@200914037@20.09.14 (120400-300565878):8)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at avms.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):1)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at avlg.c(:com.google.android.gms@200914037@20.09.14 (120400-300565878):5)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at com.google.android.gms.update.execution.InstallationIntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):13)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at com.google.android.chimera.IntentOperation.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):2)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at qsr.onHandleIntent(:com.google.android.gms@200914037@20.09.14 (120400-300565878):4)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at dua.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):5)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at dtz.run(:com.google.android.gms@200914037@20.09.14 (120400-300565878):7)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
04-07 08:31:38.868 2790 10961 E SystemUpdate: at java.lang.Thread.run(Thread.java:919)
04-07 08:31:38.868 2790 10961 I SystemUpdate: [Network,NetworkRequester] Requesting network with capabilities [12]...
04-07 08:31:38.870 2790 3795 I SystemUpdate: [Network,NetworkRequester] Network acquired.
04-07 08:31:38.873 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:31:38.902 2790 10962 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:31:38.905 2790 10961 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, -1.000).
04-07 08:31:38.936 2790 10961 I SystemUpdate: [Execution,SynchronizedUpdateEngine] start(avor{url=https://ota.googlezip.net/packages/ota-api/google_crosshatch_crosshatch/9e10c787db4a5bc29c77995893391ff0793ed2dc.zip, payload_binary_file.offset=6997, payload_binary_file.length=11587887, properties=[FILE_HASH=GLBtn9DA/kXn6xq0qVujwKbfN/+QuISnsX1Guyx5FUk=, FILE_SIZE=11587887, METADATA_HASH=/CABJ0Br0Udqqa6WbUAB29s3TsKoi8hlnC73Yt4gvbs=, METADATA_SIZE=217508]}, additionalProperties.size=4)
04-07 08:31:38.937 2790 10961 I SystemUpdate: [Execution,UpdateEngineDelegate] applyPayload()
04-07 08:31:38.947 1092 1092 I update_engine: [0407/083138.947315:INFO:prefs.cc(122)] update-state-next-operation not present in /data/misc/update_engine/prefs
04-07 08:31:38.954 1092 1092 I update_engine: [0407/083138.954142:INFO:update_attempter_android.cc(257)] Using this install plan:
04-07 08:31:38.954 1092 1092 I update_engine: [0407/083138.954434:INFO:install_plan.cc(83)] InstallPlan: new_update, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_crosshatch_crosshatch/9e10c787db4a5bc29c77995893391ff0793ed2dc.zip, payload: (size: 11587887, metadata_size: 217508, metadata signature: , hash: 18B06D9FD0C0FE45E7EB1AB4A95BA3C0A6DF37FF90B884A7B17D46BB2C791549, 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
04-07 08:31:38.955 1092 1092 I update_engine: [0407/083138.955057:INFO:metrics_utils.cc(349)] Number of Reboots during current update attempt = 0
04-07 08:31:38.955 1092 1092 I update_engine: [0407/083138.955273:INFO:metrics_utils.cc(357)] Payload Attempt Number = 1
04-07 08:31:38.956 1092 1092 I update_engine: [0407/083138.955444:INFO:metrics_utils.cc(374)] Update Monotonic Timestamp Start = 1/1/1970 0:04:41 GMT
04-07 08:31:38.956 2790 2811 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x002.
04-07 08:31:38.956 1092 1092 I update_engine: [0407/083138.956265:INFO:metrics_utils.cc(383)] Update Boot Timestamp Start = 1/1/1970 0:04:41 GMT
04-07 08:31:38.956 1092 1092 I update_engine: [0407/083138.956318:INFO:update_attempter_android.cc(566)] Scheduling an action processor start.
04-07 08:31:38.956 1092 1092 I update_engine: [0407/083138.956551:INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
04-07 08:31:38.956 1092 1092 I update_engine: [0407/083138.956640:INFO:update_boot_flags_action.cc(45)] Marking booted slot as good.
04-07 08:31:38.999 1092 1092 I update_engine: [0407/083138.998915:INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
04-07 08:31:38.999 1092 1092 I update_engine: [0407/083138.999178:INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
04-07 08:31:38.999 1092 1092 I update_engine: [0407/083138.999256:INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
04-07 08:31:38.999 1092 1092 I update_engine: [0407/083138.999300:INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
04-07 08:31:38.999 1092 1092 I update_engine: [0407/083138.999393:INFO:install_plan.cc(83)] InstallPlan: new_update, version: , source_slot: A, target_slot: B, url: https://ota.googlezip.net/packages/ota-api/google_crosshatch_crosshatch/9e10c787db4a5bc29c77995893391ff0793ed2dc.zip, payload: (size: 11587887, metadata_size: 217508, metadata signature: , hash: 18B06D9FD0C0FE45E7EB1AB4A95BA3C0A6DF37FF90B884A7B17D46BB2C791549, 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
04-07 08:31:38.999 1092 1092 I update_engine: [0407/083138.999451:INFO:download_action.cc(199)] Marking new slot as unbootable
04-07 08:31:39.040 1092 1092 I update_engine: [0407/083139.040015:INFO:multi_range_http_fetcher.cc(45)] starting first transfer
04-07 08:31:39.040 1092 1092 I update_engine: [0407/083139.040316:INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 6997+11587887
04-07 08:31:39.040 1092 1092 I update_engine: [0407/083139.040628:INFO:libcurl_http_fetcher.cc(146)] Starting/Resuming transfer
04-07 08:31:39.050 1092 1092 I update_engine: [0407/083139.050049:INFO:libcurl_http_fetcher.cc(165)] Using proxy: no
04-07 08:31:39.050 1092 1092 I update_engine: [0407/083139.050324:INFO:libcurl_http_fetcher.cc(305)] Setting up curl options for HTTPS
04-07 08:31:45.305 1092 1092 I update_engine: [0407/083145.304983:INFO:prefs.cc(122)] update-server-cert-1-2 not present in /data/misc/update_engine/prefs
04-07 08:31:45.307 1092 1092 I update_engine: [0407/083145.307011:INFO:prefs.cc(122)] update-server-cert-1-1 not present in /data/misc/update_engine/prefs
04-07 08:31:45.308 1092 1092 I update_engine: [0407/083145.308423:INFO:prefs.cc(122)] update-server-cert-1-0 not present in /data/misc/update_engine/prefs
04-07 08:31:46.267 1092 1092 I update_engine: [0407/083146.267612:INFO:certificate_checker.cc(185)] Certificate changed from 2708D6131BD40EE5CE696D24FA9E0E4D69775499A0C1E6A451E9B2277ABD007D to 6FB1A0E7467BCC4CEB0A4276D1F78CD3A97C1987D64BBAC9CE9CC625D62FF4F3.
04-07 08:31:46.441 1092 1092 I update_engine: [0407/083146.441317:INFO:delta_performer.cc(208)] Completed 0/? operations, 16384/11587887 bytes downloaded (0%), overall progress 0%
04-07 08:31:46.460 2790 2811 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.002).
04-07 08:31:46.470 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.002018.
04-07 08:31:46.486 2790 2811 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:46.697 2790 2811 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:46.719 1092 1092 I update_engine: [0407/083146.719539:INFO:delta_performer.cc(519)] Manifest size in payload matches expected value from Omaha
04-07 08:31:46.720 1092 1092 I update_engine: [0407/083146.720141:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
04-07 08:31:46.724 1092 1092 I update_engine: [0407/083146.724915:INFO:payload_verifier.cc(58)] signature blob size = 264
04-07 08:31:46.726 1092 1092 I update_engine: [0407/083146.726106:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
04-07 08:31:46.726 1092 1092 I update_engine: [0407/083146.726246:INFO:payload_metadata.cc(237)] Metadata hash signature matches value in Omaha response.
04-07 08:31:46.763 1092 1092 I update_engine: [0407/083146.763673:INFO:delta_performer.cc(1643)] Detected a 'delta' payload.
04-07 08:31:46.840 1092 1092 I update_engine: [0407/083146.840211:INFO:prefs.cc(122)] dynamic-partition-metadata-updated not present in /data/misc/update_engine/prefs
04-07 08:31:46.838 1092 1092 I update_engine: type=1400 audit(0.0:757): avc: denied { search } for name="gsi" dev="sda20" ino=16 scontext=u:r:update_engine:s0 tcontext=u:object_r:gsi_metadata_file:s0 tclass=dir permissive=1
04-07 08:31:46.843 1092 1092 I update_engine: [0407/083146.843671:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.843 1092 1092 I update_engine: [0407/083146.843809:INFO:boot_control_android.cc(343)] Added group google_dynamic_partitions_b with size 4069523456
04-07 08:31:46.845 1092 1092 I update_engine: [0407/083146.845820:INFO:boot_control_android.cc(360)] Added partition system_b to group google_dynamic_partitions_b with size 847581184
04-07 08:31:46.845 1092 1092 I update_engine: [0407/083146.845977:INFO:boot_control_android.cc(360)] Added partition vendor_b to group google_dynamic_partitions_b with size 465022976
04-07 08:31:46.846 1092 1092 I update_engine: [0407/083146.846055:INFO:boot_control_android.cc(360)] Added partition product_b to group google_dynamic_partitions_b with size 2085732352
04-07 08:31:46.853 1092 1092 I update_engine: [0407/083146.853097:INFO:dynamic_partition_control_android.cc(220)] Written metadata to /dev/block/bootdevice/by-name/system_b
04-07 08:31:46.853 1092 1092 I update_engine: [0407/083146.853794:INFO:delta_performer.cc(979)] InitPartitionMetadata done.
04-07 08:31:46.855 1092 1092 I update_engine: [0407/083146.855679:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.856 1092 1092 I update_engine: [0407/083146.855977:INFO:boot_control_android.cc(140)] boot_a is not in super partition metadata.
04-07 08:31:46.856 1092 1092 I update_engine: [0407/083146.856569:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.859 1092 1092 I update_engine: [0407/083146.859445:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:46.859 1092 1092 I update_engine: [0407/083146.859803:INFO:boot_control_android.cc(140)] boot_b is not in super partition metadata.
04-07 08:31:46.860 1092 1092 I update_engine: [0407/083146.860409:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.861 1092 1092 I update_engine: [0407/083146.861970:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.862 1092 1092 I update_engine: [0407/083146.862330:INFO:boot_control_android.cc(163)] system_a is mapped on device mapper: /dev/block/dm-0
04-07 08:31:46.864 1092 1092 I update_engine: [0407/083146.864883:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:46.930 1092 1092 I update_engine: [0407/083146.930645:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-12
04-07 08:31:46.936 1092 1092 I update_engine: [0407/083146.936254:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.937 1092 1092 I update_engine: [0407/083146.937107:INFO:boot_control_android.cc(140)] vbmeta_a is not in super partition metadata.
04-07 08:31:46.938 1092 1092 I update_engine: [0407/083146.938760:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.946 1092 1092 I update_engine: [0407/083146.946707:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:46.947 1092 1092 I update_engine: [0407/083146.947410:INFO:boot_control_android.cc(140)] vbmeta_b is not in super partition metadata.
04-07 08:31:46.948 1092 1092 I update_engine: [0407/083146.948854:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.952 1092 1092 I update_engine: [0407/083146.952310:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.953 1092 1092 I update_engine: [0407/083146.953070:INFO:boot_control_android.cc(140)] dtbo_a is not in super partition metadata.
04-07 08:31:46.954 1092 1092 I update_engine: [0407/083146.954678:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.958 1092 1092 I update_engine: [0407/083146.958133:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:46.958 1092 1092 I update_engine: [0407/083146.958642:INFO:boot_control_android.cc(140)] dtbo_b is not in super partition metadata.
04-07 08:31:46.959 1092 1092 I update_engine: [0407/083146.959814:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.962 1092 1092 I update_engine: [0407/083146.962710:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:46.963 1092 1092 I update_engine: [0407/083146.963575:INFO:boot_control_android.cc(163)] product_a is mapped on device mapper: /dev/block/dm-2
04-07 08:31:46.966 1092 1092 I update_engine: [0407/083146.966675:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.025 1092 1092 I update_engine: [0407/083147.025296:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-13
04-07 08:31:47.032 1092 1092 I update_engine: [0407/083147.032288:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.033 1092 1092 I update_engine: [0407/083147.033432:INFO:boot_control_android.cc(163)] vendor_a is mapped on device mapper: /dev/block/dm-1
04-07 08:31:47.037 1092 1092 I update_engine: [0407/083147.037855:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.113 1092 1092 I update_engine: [0407/083147.112982:INFO:dynamic_partition_control_android.cc(77)] Succesfully mapped vendor_b to device mapper (force_writable = 1); device path at /dev/block/dm-14
04-07 08:31:47.120 1092 1092 I update_engine: [0407/083147.120749:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.121 1092 1092 I update_engine: [0407/083147.121864:INFO:boot_control_android.cc(140)] abl_a is not in super partition metadata.
04-07 08:31:47.123 1092 1092 I update_engine: [0407/083147.123289:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.127 1092 1092 I update_engine: [0407/083147.127013:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.127 1092 1092 I update_engine: [0407/083147.127582:INFO:boot_control_android.cc(140)] abl_b is not in super partition metadata.
04-07 08:31:47.129 1092 1092 I update_engine: [0407/083147.129093:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.132 1092 1092 I update_engine: [0407/083147.132831:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.133 1092 1092 I update_engine: [0407/083147.133607:INFO:boot_control_android.cc(140)] aop_a is not in super partition metadata.
04-07 08:31:47.135 1092 1092 I update_engine: [0407/083147.135101:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.138 1092 1092 I update_engine: [0407/083147.138838:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.139 1092 1092 I update_engine: [0407/083147.139462:INFO:boot_control_android.cc(140)] aop_b is not in super partition metadata.
04-07 08:31:47.140 1092 1092 I update_engine: [0407/083147.140577:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.143 1092 1092 I update_engine: [0407/083147.143193:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.144 1092 1092 I update_engine: [0407/083147.144176:INFO:boot_control_android.cc(140)] cmnlib_a is not in super partition metadata.
04-07 08:31:47.145 1092 1092 I update_engine: [0407/083147.145336:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.148 1092 1092 I update_engine: [0407/083147.148070:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.148 1092 1092 I update_engine: [0407/083147.148660:INFO:boot_control_android.cc(140)] cmnlib_b is not in super partition metadata.
04-07 08:31:47.149 1092 1092 I update_engine: [0407/083147.149761:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.152 1092 1092 I update_engine: [0407/083147.152668:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.153 1092 1092 I update_engine: [0407/083147.153457:INFO:boot_control_android.cc(140)] cmnlib64_a is not in super partition metadata.
04-07 08:31:47.154 1092 1092 I update_engine: [0407/083147.154731:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.157 1092 1092 I update_engine: [0407/083147.157859:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.158 1092 1092 I update_engine: [0407/083147.158510:INFO:boot_control_android.cc(140)] cmnlib64_b is not in super partition metadata.
04-07 08:31:47.160 1092 1092 I update_engine: [0407/083147.160082:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.163 1092 1092 I update_engine: [0407/083147.163128:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.163 1092 1092 I update_engine: [0407/083147.163672:INFO:boot_control_android.cc(140)] devcfg_a is not in super partition metadata.
04-07 08:31:47.164 1092 1092 I update_engine: [0407/083147.164817:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.168 1092 1092 I update_engine: [0407/083147.168056:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.168 1092 1092 I update_engine: [0407/083147.168714:INFO:boot_control_android.cc(140)] devcfg_b is not in super partition metadata.
04-07 08:31:47.170 1092 1092 I update_engine: [0407/083147.170112:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.173 1092 1092 I update_engine: [0407/083147.173307:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.174 1092 1092 I update_engine: [0407/083147.174394:INFO:boot_control_android.cc(140)] hyp_a is not in super partition metadata.
04-07 08:31:47.175 1092 1092 I update_engine: [0407/083147.175504:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.178 1092 1092 I update_engine: [0407/083147.178114:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.178 1092 1092 I update_engine: [0407/083147.178756:INFO:boot_control_android.cc(140)] hyp_b is not in super partition metadata.
04-07 08:31:47.179 1092 1092 I update_engine: [0407/083147.179810:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.182 1092 1092 I update_engine: [0407/083147.182446:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.183 1092 1092 I update_engine: [0407/083147.182980:INFO:boot_control_android.cc(140)] keymaster_a is not in super partition metadata.
04-07 08:31:47.184 1092 1092 I update_engine: [0407/083147.183995:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.189 1092 1092 I update_engine: [0407/083147.189488:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.190 1092 1092 I update_engine: [0407/083147.190086:INFO:boot_control_android.cc(140)] keymaster_b is not in super partition metadata.
04-07 08:31:47.191 1092 1092 I update_engine: [0407/083147.191316:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.194 1092 1092 I update_engine: [0407/083147.194537:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.195 1092 1092 I update_engine: [0407/083147.195178:INFO:boot_control_android.cc(140)] qupfw_a is not in super partition metadata.
04-07 08:31:47.196 1092 1092 I update_engine: [0407/083147.196377:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.199 1092 1092 I update_engine: [0407/083147.199233:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.200 1092 1092 I update_engine: [0407/083147.200296:INFO:boot_control_android.cc(140)] qupfw_b is not in super partition metadata.
04-07 08:31:47.201 1092 1092 I update_engine: [0407/083147.201385:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.204 1092 1092 I update_engine: [0407/083147.204505:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.205 1092 1092 I update_engine: [0407/083147.205191:INFO:boot_control_android.cc(140)] tz_a is not in super partition metadata.
04-07 08:31:47.206 1092 1092 I update_engine: [0407/083147.206394:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.209 1092 1092 I update_engine: [0407/083147.209424:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.209 1092 1092 I update_engine: [0407/083147.209940:INFO:boot_control_android.cc(140)] tz_b is not in super partition metadata.
04-07 08:31:47.211 1092 1092 I update_engine: [0407/083147.211229:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.214 1092 1092 I update_engine: [0407/083147.214488:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.215 1092 1092 I update_engine: [0407/083147.215125:INFO:boot_control_android.cc(140)] xbl_a is not in super partition metadata.
04-07 08:31:47.216 1092 1092 I update_engine: [0407/083147.216618:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.219 1092 1092 I update_engine: [0407/083147.219750:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.220 1092 1092 I update_engine: [0407/083147.220182:INFO:boot_control_android.cc(140)] xbl_b is not in super partition metadata.
04-07 08:31:47.221 1092 1092 I update_engine: [0407/083147.221332:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.223 1092 1092 I update_engine: [0407/083147.223824:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.224 1092 1092 I update_engine: [0407/083147.224891:INFO:boot_control_android.cc(140)] xbl_config_a is not in super partition metadata.
04-07 08:31:47.225 1092 1092 I update_engine: [0407/083147.225789:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.227 1092 1092 I update_engine: [0407/083147.227862:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.228 1092 1092 I update_engine: [0407/083147.228308:INFO:boot_control_android.cc(140)] xbl_config_b is not in super partition metadata.
04-07 08:31:47.229 1092 1092 I update_engine: [0407/083147.229148:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.231 1092 1092 I update_engine: [0407/083147.231286:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.231 1092 1092 I update_engine: [0407/083147.231726:INFO:boot_control_android.cc(140)] modem_a is not in super partition metadata.
04-07 08:31:47.232 1092 1092 I update_engine: [0407/083147.232611:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.235 1092 1092 I update_engine: [0407/083147.235239:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot B in /dev/block/bootdevice/by-name/system_b
04-07 08:31:47.235 1092 1092 I update_engine: [0407/083147.235702:INFO:boot_control_android.cc(140)] modem_b is not in super partition metadata.
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.236978:INFO:dynamic_partition_control_android.cc(197)] Loaded metadata from slot A in /dev/block/bootdevice/by-name/system_a
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.237260:INFO:delta_performer.cc(450)] PartitionInfo old boot sha256: M2LqTZDsYgdhniRteb3YeEaqJrocW2qUMfBfiVPKsAg= size: 67108864
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.237364:INFO:delta_performer.cc(450)] PartitionInfo new boot sha256: j05Vbw6Jg7sWhp7GChCn1r4h160xUAcfbGZYRSwFuJI= size: 67108864
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.237442:INFO:delta_performer.cc(450)] PartitionInfo old system sha256: o2VQTS1YaB6hWjosiGg14EcG855v4Tku8HueNLBah0c= size: 847577088
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.237555:INFO:delta_performer.cc(450)] PartitionInfo new system sha256: uXcWMlkzrm0tszEGD9Kk/KeLHuchbjS3RZR+NZ8SA/k= size: 847581184
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.237851:INFO:delta_performer.cc(450)] PartitionInfo old vbmeta sha256: SLzPi55SGvN6fdx1E5ZhByZCDUGx8E/UKoBau1ZqwW8= size: 4096
04-07 08:31:47.237 1092 1092 I update_engine: [0407/083147.237937:INFO:delta_performer.cc(450)] PartitionInfo new vbmeta sha256: WCGTuoGr4PuyUYlSt54dV+jvg5aW5xLz0kI2edNlrV8= size: 4096
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238051:INFO:delta_performer.cc(450)] PartitionInfo old dtbo sha256: ZnxicFm18KGTdBTRHMEPXTuoHrFgTD5iD5b9YZK5/Mw= size: 8388608
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238122:INFO:delta_performer.cc(450)] PartitionInfo new dtbo sha256: 1pCeBTJsyWdMwOT/iFoU+A7f0EdoY/lO1EzYfiY0kck= size: 8388608
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238190:INFO:delta_performer.cc(450)] PartitionInfo old product sha256: xBhxz6QWfD4k1Js43tj+pgE/5EEIgcD/U0KJI+hding= size: 2085740544
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238254:INFO:delta_performer.cc(450)] PartitionInfo new product sha256: SZWSxINaEz2NrTJXa/CWyXZ6XvsS37FqJeUkDfhqMeE= size: 2085732352
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238321:INFO:delta_performer.cc(450)] PartitionInfo old vendor sha256: e55jDOHIqt4K0O3zLeLOuCIUmCySjh3xyDhnA2DaG5U= size: 465022976
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238387:INFO:delta_performer.cc(450)] PartitionInfo new vendor sha256: 7BpGMw4rSYTFrKayMuMxWbaP8VdNRth5Z/HPohkDYe0= size: 465022976
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238457:INFO:delta_performer.cc(450)] PartitionInfo old abl sha256: Tl0+QYjndZb9nCaYM2x2MhDu/Ff0RzkCej3DS33bzdw= size: 1048576
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238521:INFO:delta_performer.cc(450)] PartitionInfo new abl sha256: Tl0+QYjndZb9nCaYM2x2MhDu/Ff0RzkCej3DS33bzdw= size: 1048576
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238588:INFO:delta_performer.cc(450)] PartitionInfo old aop sha256: 4pTt/MJhkOCInD4VSKd/MPitOQkni/LHVGS/ZZ83f80= size: 188416
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238685:INFO:delta_performer.cc(450)] PartitionInfo new aop sha256: 4pTt/MJhkOCInD4VSKd/MPitOQkni/LHVGS/ZZ83f80= size: 188416
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238829:INFO:delta_performer.cc(450)] PartitionInfo old cmnlib sha256: Qvc/ovmlzMVNV3OwvhwsnY7KEjGY2LVnMz1vCGnK1fw= size: 380928
04-07 08:31:47.238 1092 1092 I update_engine: [0407/083147.238904:INFO:delta_performer.cc(450)] PartitionInfo new cmnlib sha256: Qvc/ovmlzMVNV3OwvhwsnY7KEjGY2LVnMz1vCGnK1fw= size: 380928
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.238975:INFO:delta_performer.cc(450)] PartitionInfo old cmnlib64 sha256: bKG0zawXzJkSqgiroiRMxPk6IE69QNm92kjyflBosGQ= size: 499712
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239111:INFO:delta_performer.cc(450)] PartitionInfo new cmnlib64 sha256: bKG0zawXzJkSqgiroiRMxPk6IE69QNm92kjyflBosGQ= size: 499712
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239183:INFO:delta_performer.cc(450)] PartitionInfo old devcfg sha256: LFZsp2bTKGS0fCc7Gyeqpdtbg7hApbQvbEh/QB+r0JA= size: 40960
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239250:INFO:delta_performer.cc(450)] PartitionInfo new devcfg sha256: LFZsp2bTKGS0fCc7Gyeqpdtbg7hApbQvbEh/QB+r0JA= size: 40960
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239388:INFO:delta_performer.cc(450)] PartitionInfo old hyp sha256: Avo1HXSJFov8V3O9izfiDkuhNm0egVIvrEfiiaRnL8I= size: 397312
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239460:INFO:delta_performer.cc(450)] PartitionInfo new hyp sha256: Avo1HXSJFov8V3O9izfiDkuhNm0egVIvrEfiiaRnL8I= size: 397312
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239602:INFO:delta_performer.cc(450)] PartitionInfo old keymaster sha256: Gf2qs1Xd0HfNtVyEiNjfhymp5DA43cNGTL2kcOM3KsY= size: 221184
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239712:INFO:delta_performer.cc(450)] PartitionInfo new keymaster sha256: Gf2qs1Xd0HfNtVyEiNjfhymp5DA43cNGTL2kcOM3KsY= size: 221184
04-07 08:31:47.239 1092 1092 I update_engine: [0407/083147.239833:INFO:delta_performer.cc(450)] PartitionInfo old qupfw sha256: Mt5UpFvRHdMgbFEBi1fosvy/4IkMlaT+zJGw+H2GTyI= size: 65536
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.239977:INFO:delta_performer.cc(450)] PartitionInfo new qupfw sha256: Mt5UpFvRHdMgbFEBi1fosvy/4IkMlaT+zJGw+H2GTyI= size: 65536
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240050:INFO:delta_performer.cc(450)] PartitionInfo old tz sha256: yEmqZAeK9RldOJEL4iM4tO6n5xvmJkIlc3XK/HpB8Ow= size: 2064384
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240163:INFO:delta_performer.cc(450)] PartitionInfo new tz sha256: yEmqZAeK9RldOJEL4iM4tO6n5xvmJkIlc3XK/HpB8Ow= size: 2064384
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240237:INFO:delta_performer.cc(450)] PartitionInfo old xbl sha256: LueV+oBfjNfyEH3k6hHO8n4UBfh7lPvnuoCgbrRLT4E= size: 3661824
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240348:INFO:delta_performer.cc(450)] PartitionInfo new xbl sha256: LueV+oBfjNfyEH3k6hHO8n4UBfh7lPvnuoCgbrRLT4E= size: 3661824
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240421:INFO:delta_performer.cc(450)] PartitionInfo old xbl_config sha256: py2pRmpCUcnkjOYDR7ymnNhv1vZ3T6Vux+czI1mBodw= size: 98304
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240554:INFO:delta_performer.cc(450)] PartitionInfo new xbl_config sha256: py2pRmpCUcnkjOYDR7ymnNhv1vZ3T6Vux+czI1mBodw= size: 98304
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240631:INFO:delta_performer.cc(450)] PartitionInfo old modem sha256: f6AgkXg6Mbv/MOkSq/KdKeoOHVb97D+qmYDx2Ug9i/E= size: 73134080
04-07 08:31:47.240 1092 1092 I update_engine: [0407/083147.240756:INFO:delta_performer.cc(450)] PartitionInfo new modem sha256: f6AgkXg6Mbv/MOkSq/KdKeoOHVb97D+qmYDx2Ug9i/E= size: 73134080
04-07 08:31:47.242 1092 1092 I update_engine: [0407/083147.242242:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/boot_b partition without O_DSYNC
04-07 08:31:47.246 1092 1092 I update_engine: [0407/083147.246455:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:31:47.246 1092 1092 I update_engine: [0407/083147.246711:INFO:delta_performer.cc(396)] Applying 24 operations to partition "boot"
04-07 08:31:47.246 1092 1092 I update_engine: [0407/083147.246922:INFO:delta_performer.cc(654)] Starting to apply update payload operations
04-07 08:31:47.287 2790 2811 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:47.291 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:47.403 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:47.486 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.126).
04-07 08:31:47.503 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.126440.
04-07 08:31:47.529 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:47.542 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:50.780 1092 1092 I update_engine: [0407/083150.780162:INFO:delta_performer.cc(384)] Opening /dev/block/dm-12 partition without O_DSYNC
04-07 08:31:50.782 1092 1092 I update_engine: [0407/083150.782560:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:31:50.782 1092 1092 I update_engine: [0407/083150.782721:INFO:delta_performer.cc(396)] Applying 829 operations to partition "system"
04-07 08:31:51.602 1092 1092 I update_engine: [0407/083151.601900:INFO:delta_performer.cc(208)] Completed 75/3741 operations (2%), 2155675/11587887 bytes downloaded (18%), overall progress 10%
04-07 08:31:52.569 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.193).
04-07 08:31:52.576 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.192573.
04-07 08:31:52.588 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:53.488 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:53.840 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.214).
04-07 08:31:53.848 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.214263.
04-07 08:31:53.862 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:53.864 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:53.865 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:54.271 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:54.813 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:55.456 1092 1092 I update_engine: [0407/083155.456240:INFO:delta_performer.cc(208)] Completed 449/3741 operations (12%), 3465516/11587887 bytes downloaded (29%), overall progress 20%
04-07 08:31:55.535 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.302).
04-07 08:31:55.546 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.302495.
04-07 08:31:55.563 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:55.576 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.641 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.370).
04-07 08:31:56.653 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.370363.
04-07 08:31:56.671 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.730 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.733 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.734 1092 1092 I update_engine: [0407/083156.734130:INFO:delta_performer.cc(208)] Completed 600/3741 operations (16%), 5103923/11587887 bytes downloaded (44%), overall progress 30%
04-07 08:31:56.735 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.737 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.740 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:56.750 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:57.279 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:57.488 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:57.742 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.551).
04-07 08:31:57.753 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.551339.
04-07 08:31:57.769 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:58.870 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.563).
04-07 08:31:58.881 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.562650.
04-07 08:31:58.898 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:31:59.238 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:00.081 1092 1092 I update_engine: [0407/083200.080878:INFO:delta_performer.cc(208)] Completed 824/3741 operations (22%), 6775074/11587887 bytes downloaded (58%), overall progress 40%
04-07 08:32:00.674 1092 1092 I update_engine: [0407/083200.673893:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/vbmeta_b partition without O_DSYNC
04-07 08:32:00.676 1092 1092 I update_engine: [0407/083200.676003:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:00.676 1092 1092 I update_engine: [0407/083200.676201:INFO:delta_performer.cc(396)] Applying 1 operations to partition "vbmeta"
04-07 08:32:00.699 1092 1092 I update_engine: [0407/083200.699217:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/dtbo_b partition without O_DSYNC
04-07 08:32:00.701 1092 1092 I update_engine: [0407/083200.701293:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:00.701 1092 1092 I update_engine: [0407/083200.701429:INFO:delta_performer.cc(396)] Applying 7 operations to partition "dtbo"
04-07 08:32:00.743 1092 1092 I update_engine: [0407/083200.743611:INFO:delta_performer.cc(384)] Opening /dev/block/dm-13 partition without O_DSYNC
04-07 08:32:00.745 1092 1092 I update_engine: [0407/083200.745218:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:00.745 1092 1092 I update_engine: [0407/083200.745311:INFO:delta_performer.cc(396)] Applying 1273 operations to partition "product"
04-07 08:32:06.803 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:32:06.820 2790 11014 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:32:09.921 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.594).
04-07 08:32:09.934 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.593756.
04-07 08:32:09.950 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:10.785 1092 1092 I update_engine: [0407/083210.785278:INFO:delta_performer.cc(208)] Completed 1572/3741 operations (42%), 6889762/11587887 bytes downloaded (59%), overall progress 50%
04-07 08:32:11.495 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.605).
04-07 08:32:11.510 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.605067.
04-07 08:32:11.526 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:11.709 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:12.197 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:12.199 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:12.202 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:12.206 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:13.141 2790 7808 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.684).
04-07 08:32:13.151 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.684245.
04-07 08:32:13.167 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:13.886 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:14.025 1092 1092 I update_engine: [0407/083214.025615:INFO:delta_performer.cc(208)] Completed 1796/3741 operations (48%), 8447083/11587887 bytes downloaded (72%), overall progress 60%
04-07 08:32:15.666 2790 7808 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.740).
04-07 08:32:15.676 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.739747.
04-07 08:32:15.690 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:15.693 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:15.695 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:15.696 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:17.428 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.785).
04-07 08:32:17.504 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.784630.
04-07 08:32:17.602 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:17.609 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:18.020 1092 1092 I update_engine: [0407/083218.020836:INFO:delta_performer.cc(208)] Completed 1974/3741 operations (52%), 10199316/11587887 bytes downloaded (88%), overall progress 70%
04-07 08:32:18.031 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:18.034 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:18.044 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:20.681 1092 1092 I update_engine: [0407/083220.681268:INFO:delta_performer.cc(384)] Opening /dev/block/dm-14 partition without O_DSYNC
04-07 08:32:20.683 1092 1092 I update_engine: [0407/083220.683061:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:20.683 1092 1092 I update_engine: [0407/083220.683257:INFO:delta_performer.cc(396)] Applying 369 operations to partition "vendor"
04-07 08:32:22.490 2790 7808 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.909).
04-07 08:32:22.509 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.909124.
04-07 08:32:22.529 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:23.434 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:24.508 2790 7808 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.942).
04-07 08:32:24.510 1092 1092 I update_engine: [0407/083224.510699:INFO:delta_performer.cc(208)] Completed 2461/3741 operations (65%), 11133185/11587887 bytes downloaded (96%), overall progress 80%
04-07 08:32:24.520 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.941570.
04-07 08:32:24.541 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:24.550 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x003.
04-07 08:32:25.337 1092 1092 I update_engine: [0407/083225.337415:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/abl_b partition without O_DSYNC
04-07 08:32:25.339 1092 1092 I update_engine: [0407/083225.339296:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.339 1092 1092 I update_engine: [0407/083225.339457:INFO:delta_performer.cc(396)] Applying 1 operations to partition "abl"
04-07 08:32:25.370 1092 1092 I update_engine: [0407/083225.370375:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/aop_b partition without O_DSYNC
04-07 08:32:25.372 1092 1092 I update_engine: [0407/083225.372311:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.372 1092 1092 I update_engine: [0407/083225.372473:INFO:delta_performer.cc(396)] Applying 3 operations to partition "aop"
04-07 08:32:25.391 1092 1092 I update_engine: [0407/083225.391381:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/cmnlib_b partition without O_DSYNC
04-07 08:32:25.392 1092 1092 I update_engine: [0407/083225.392927:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.393 1092 1092 I update_engine: [0407/083225.393041:INFO:delta_performer.cc(396)] Applying 1 operations to partition "cmnlib"
04-07 08:32:25.409 1092 1092 I update_engine: [0407/083225.409782:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/cmnlib64_b partition without O_DSYNC
04-07 08:32:25.411 1092 1092 I update_engine: [0407/083225.411440:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.411 1092 1092 I update_engine: [0407/083225.411589:INFO:delta_performer.cc(396)] Applying 1 operations to partition "cmnlib64"
04-07 08:32:25.428 1092 1092 I update_engine: [0407/083225.428610:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/devcfg_b partition without O_DSYNC
04-07 08:32:25.430 1092 1092 I update_engine: [0407/083225.430178:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.430 1092 1092 I update_engine: [0407/083225.430278:INFO:delta_performer.cc(396)] Applying 1 operations to partition "devcfg"
04-07 08:32:25.442 1092 1092 I update_engine: [0407/083225.442129:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/hyp_b partition without O_DSYNC
04-07 08:32:25.443 1092 1092 I update_engine: [0407/083225.443577:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.443 1092 1092 I update_engine: [0407/083225.443655:INFO:delta_performer.cc(396)] Applying 2 operations to partition "hyp"
04-07 08:32:25.459 1092 1092 I update_engine: [0407/083225.459779:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/keymaster_b partition without O_DSYNC
04-07 08:32:25.461 1092 1092 I update_engine: [0407/083225.461417:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.461 1092 1092 I update_engine: [0407/083225.461559:INFO:delta_performer.cc(396)] Applying 3 operations to partition "keymaster"
04-07 08:32:25.479 1092 1092 I update_engine: [0407/083225.479807:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/qupfw_b partition without O_DSYNC
04-07 08:32:25.481 1092 1092 I update_engine: [0407/083225.481495:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.481 1092 1092 I update_engine: [0407/083225.481606:INFO:delta_performer.cc(396)] Applying 1 operations to partition "qupfw"
04-07 08:32:25.487 1092 1092 I update_engine: [0407/083225.487444:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/tz_b partition without O_DSYNC
04-07 08:32:25.489 1092 1092 I update_engine: [0407/083225.489009:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.489 1092 1092 I update_engine: [0407/083225.489117:INFO:delta_performer.cc(396)] Applying 19 operations to partition "tz"
04-07 08:32:25.556 1092 1092 I update_engine: [0407/083225.556649:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/xbl_b partition without O_DSYNC
04-07 08:32:25.558 1092 1092 I update_engine: [0407/083225.558397:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.558 1092 1092 I update_engine: [0407/083225.558546:INFO:delta_performer.cc(396)] Applying 7 operations to partition "xbl"
04-07 08:32:25.644 1092 1092 I update_engine: [0407/083225.644589:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/xbl_config_b partition without O_DSYNC
04-07 08:32:25.646 1092 1092 I update_engine: [0407/083225.646447:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.646 1092 1092 I update_engine: [0407/083225.646589:INFO:delta_performer.cc(396)] Applying 1 operations to partition "xbl_config"
04-07 08:32:25.661 1092 1092 I update_engine: [0407/083225.661755:INFO:delta_performer.cc(384)] Opening /dev/block/bootdevice/by-name/modem_b partition without O_DSYNC
04-07 08:32:25.663 1092 1092 I update_engine: [0407/083225.663172:INFO:delta_performer.cc(127)] Caching writes.
04-07 08:32:25.663 1092 1092 I update_engine: [0407/083225.663254:INFO:delta_performer.cc(396)] Applying 1198 operations to partition "modem"
04-07 08:32:26.709 1092 1092 I update_engine: [0407/083226.709617:INFO:delta_performer.cc(208)] Completed 2993/3741 operations (80%), 11587887/11587887 bytes downloaded (100%), overall progress 90%
04-07 08:32:28.494 1092 1092 I update_engine: [0407/083228.493933:INFO:delta_performer.cc(208)] Completed 3741/3741 operations (100%), 11587887/11587887 bytes downloaded (100%), overall progress 100%
04-07 08:32:28.494 1092 1092 I update_engine: [0407/083228.494775:INFO:delta_performer.cc(1602)] Extracted signature data of size 264 at 11369851
04-07 08:32:28.495 1092 1092 I update_engine: [0407/083228.495342:INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
04-07 08:32:28.495 1092 1092 I update_engine: [0407/083228.495410:INFO:libcurl_http_fetcher.cc(548)] Requesting libcurl to terminate transfer.
04-07 08:32:28.500 1092 1092 I update_engine: [0407/083228.500582:INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
04-07 08:32:28.500 1092 1092 I update_engine: [0407/083228.500742:INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 206
04-07 08:32:28.500 1092 1092 I update_engine: [0407/083228.500787:INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
04-07 08:32:28.666 1092 1092 I update_engine: [0407/083228.666401:INFO:delta_performer.cc(1612)] Verifying using public key: /etc/update_engine/update-payload-key.pub.pem
04-07 08:32:28.668 1092 1092 I update_engine: [0407/083228.668556:INFO:payload_verifier.cc(58)] signature blob size = 264
04-07 08:32:28.669 1092 1092 I update_engine: [0407/083228.669108:INFO:payload_verifier.cc(78)] Verified correct signature 1 out of 1 signatures.
04-07 08:32:28.669 1092 1092 I update_engine: [0407/083228.669198:INFO:delta_performer.cc(1829)] Payload hash matches value in payload.
04-07 08:32:28.672 1092 1092 I update_engine: [0407/083228.672631:INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
04-07 08:32:28.672 1092 1092 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::PUFFDIFF.Duration recorded 9 samples, mean = 581.1
04-07 08:32:28.672 1092 1092 I update_engine: 0 ------------------------------------O (1 = 11.1%)
04-07 08:32:28.672 1092 1092 I update_engine: 10 ...
04-07 08:32:28.672 1092 1092 I update_engine: 57 ------------------------------------------------------------------------O (2 = 22.2%) {11.1%}
04-07 08:32:28.672 1092 1092 I update_engine: 101 ------------------------------------O (1 = 11.1%) {33.3%}
04-07 08:32:28.672 1092 1092 I update_engine: 179 ------------------------------------------------------------------------O (2 = 22.2%) {44.4%}
04-07 08:32:28.672 1092 1092 I update_engine: 317 O (0 = 0.0%) {66.7%}
04-07 08:32:28.672 1092 1092 I update_engine: 561 ------------------------------------O (1 = 11.1%) {66.7%}
04-07 08:32:28.672 1092 1092 I update_engine: 993 ------------------------------------O (1 = 11.1%) {77.8%}
04-07 08:32:28.672 1092 1092 I update_engine: 1758 ------------------------------------O (1 = 11.1%) {88.9%}
04-07 08:32:28.672 1092 1092 I update_engine: 3112 ...
04-07 08:32:28.672 1092 1092 I update_engine:
04-07 08:32:28.672 1092 1092 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 2 samples, mean = 1.5
04-07 08:32:28.672 1092 1092 I update_engine: 0 ------------------------------------------------------------------------O (2 = 100.0%)
04-07 08:32:28.672 1092 1092 I update_engine: 10 ...
04-07 08:32:28.672 1092 1092 I update_engine:
04-07 08:32:28.672 1092 1092 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::SOURCE_BSDIFF.Duration recorded 363 samples, mean = 14.7
04-07 08:32:28.672 1092 1092 I update_engine: 0 ------------------------------------------------------------------------O (297 = 81.8%)
04-07 08:32:28.672 1092 1092 I update_engine: 10 --------O (34 = 9.4%) {81.8%}
04-07 08:32:28.672 1092 1092 I update_engine: 18 ---O (13 = 3.6%) {91.2%}
04-07 08:32:28.672 1092 1092 I update_engine: 32 -O (4 = 1.1%) {94.8%}
04-07 08:32:28.672 1092 1092 I update_engine: 57 -O (5 = 1.4%) {95.9%}
04-07 08:32:28.672 1092 1092 I update_engine: 101 --O (7 = 1.9%) {97.2%}
04-07 08:32:28.672 1092 1092 I update_engine: 179 O (1 = 0.3%) {99.2%}
04-07 08:32:28.672 1092 1092 I update_engine: 317 O (2 = 0.6%) {99.4%}
04-07 08:32:28.672 1092 1092 I update_engine: 561 ...
04-07 08:32:28.672 1092 1092 I update_engine:
04-07 08:32:28.672 1092 1092 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::SOURCE_COPY.Duration recorded 2718 samples, mean = 9.9
04-07 08:32:28.672 1092 1092 I update_engine: 0 ------------------------------------------------------------------------O (1280 = 47.1%)
04-07 08:32:28.672 1092 1092 I update_engine: 10 ---------------------------------------------------------------------O (1219 = 44.8%) {47.1%}
04-07 08:32:28.672 1092 1092 I update_engine: 18 -----------O (203 = 7.5%) {91.9%}
04-07 08:32:28.672 1092 1092 I update_engine: 32 -O (15 = 0.6%) {99.4%}
04-07 08:32:28.672 1092 1092 I update_engine: 57 O (1 = 0.0%) {100.0%}
04-07 08:32:28.672 1092 1092 I update_engine: 101 ...
04-07 08:32:28.672 1092 1092 I update_engine:
04-07 08:32:28.672 1092 1092 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::ZERO_OR_DISCARD.Duration recorded 649 samples, mean = 0.4
04-07 08:32:28.672 1092 1092 I update_engine: 0 ------------------------------------------------------------------------O (647 = 99.7%)
04-07 08:32:28.672 1092 1092 I update_engine: 10 O (1 = 0.2%) {99.7%}
04-07 08:32:28.672 1092 1092 I update_engine: 18 ...
04-07 08:32:28.672 1092 1092 I update_engine: 57 O (1 = 0.2%) {99.8%}
04-07 08:32:28.672 1092 1092 I update_engine: 101 ...
04-07 08:32:28.672 1092 1092 I update_engine:
04-07 08:32:28.672 1092 1092 I update_engine:
04-07 08:32:28.677 2790 7808 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
04-07 08:32:28.686 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x211, isSetupWizard=false.
04-07 08:32:28.699 1092 1092 I update_engine: [0407/083228.699869:INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
04-07 08:32:28.700 1092 1092 I update_engine: [0407/083228.700136:INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
04-07 08:32:28.700 1092 1092 I update_engine: [0407/083228.700291:INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (boot) on device /dev/block/bootdevice/by-name/boot_b
04-07 08:32:28.706 2790 7808 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:32:28.904 1092 1092 I update_engine: [0407/083228.904157:INFO:filesystem_verifier_action.cc(237)] Hash of boot: j05Vbw6Jg7sWhp7GChCn1r4h160xUAcfbGZYRSwFuJI=
04-07 08:32:28.926 1092 1092 I update_engine: [0407/083228.926242:INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (system) on device /dev/block/dm-12
04-07 08:32:32.774 1092 1092 I update_engine: [0407/083232.774331:INFO:verity_writer_android.cc(93)] Writing verity hash tree to /dev/block/dm-12
04-07 08:32:32.807 1092 1092 I update_engine: [0407/083232.807172:INFO:verity_writer_android.cc(105)] Writing verity FEC to /dev/block/dm-12
04-07 08:33:06.711 2790 11063 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:33:06.820 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:33:06.840 2790 11063 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:33:19.928 1092 1092 I update_engine: [0407/083319.928578:INFO:filesystem_verifier_action.cc(237)] Hash of system: uXcWMlkzrm0tszEGD9Kk/KeLHuchbjS3RZR+NZ8SA/k=
04-07 08:33:20.310 1092 1092 I update_engine: [0407/083320.310133:INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (vbmeta) on device /dev/block/bootdevice/by-name/vbmeta_b
04-07 08:33:20.315 1092 1092 I update_engine: [0407/083320.314994:INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: WCGTuoGr4PuyUYlSt54dV+jvg5aW5xLz0kI2edNlrV8=
04-07 08:33:20.315 1092 1092 I update_engine: [0407/083320.315216:INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (dtbo) on device /dev/block/bootdevice/by-name/dtbo_b
04-07 08:33:20.346 1092 1092 I update_engine: [0407/083320.345933:INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: 1pCeBTJsyWdMwOT/iFoU+A7f0EdoY/lO1EzYfiY0kck=
04-07 08:33:20.350 1092 1092 I update_engine: [0407/083320.350033:INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (product) on device /dev/block/dm-13
04-07 08:33:29.951 1092 1092 I update_engine: [0407/083329.951295:INFO:verity_writer_android.cc(93)] Writing verity hash tree to /dev/block/dm-13
04-07 08:33:30.041 1092 1092 I update_engine: [0407/083330.041424:INFO:verity_writer_android.cc(105)] Writing verity FEC to /dev/block/dm-13
04-07 08:34:01.614 2790 11124 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:34:01.619 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x211, isSetupWizard=false.
04-07 08:34:06.800 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:34:06.807 2790 11138 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:34:42.911 2790 11173 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:35:06.810 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:35:06.840 2790 11186 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:35:25.168 1092 1092 I update_engine: [0407/083525.168082:INFO:filesystem_verifier_action.cc(237)] Hash of product: SZWSxINaEz2NrTJXa/CWyXZ6XvsS37FqJeUkDfhqMeE=
04-07 08:35:25.730 1092 1092 I update_engine: [0407/083525.730531:INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (vendor) on device /dev/block/dm-14
04-07 08:35:27.824 1092 1092 I update_engine: [0407/083527.824205:INFO:verity_writer_android.cc(93)] Writing verity hash tree to /dev/block/dm-14
04-07 08:35:27.846 1092 1092 I update_engine: [0407/083527.846716:INFO:verity_writer_android.cc(105)] Writing verity FEC to /dev/block/dm-14
04-07 08:35:52.853 1092 1092 I update_engine: [0407/083552.853385:INFO:filesystem_verifier_action.cc(237)] Hash of vendor: 7BpGMw4rSYTFrKayMuMxWbaP8VdNRth5Z/HPohkDYe0=
04-07 08:35:53.041 1092 1092 I update_engine: [0407/083553.041378:INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (abl) on device /dev/block/bootdevice/by-name/abl_b
04-07 08:35:53.063 1092 1092 I update_engine: [0407/083553.063302:INFO:filesystem_verifier_action.cc(237)] Hash of abl: Tl0+QYjndZb9nCaYM2x2MhDu/Ff0RzkCej3DS33bzdw=
04-07 08:35:53.064 1092 1092 I update_engine: [0407/083553.064031:INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (aop) on device /dev/block/bootdevice/by-name/aop_b
04-07 08:35:53.066 1092 1092 I update_engine: [0407/083553.066617:INFO:filesystem_verifier_action.cc(237)] Hash of aop: 4pTt/MJhkOCInD4VSKd/MPitOQkni/LHVGS/ZZ83f80=
04-07 08:35:53.066 1092 1092 I update_engine: [0407/083553.066843:INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (cmnlib) on device /dev/block/bootdevice/by-name/cmnlib_b
04-07 08:35:53.069 1092 1092 I update_engine: [0407/083553.069506:INFO:filesystem_verifier_action.cc(237)] Hash of cmnlib: Qvc/ovmlzMVNV3OwvhwsnY7KEjGY2LVnMz1vCGnK1fw=
04-07 08:35:53.069 1092 1092 I update_engine: [0407/083553.069731:INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (cmnlib64) on device /dev/block/bootdevice/by-name/cmnlib64_b
04-07 08:35:53.072 1092 1092 I update_engine: [0407/083553.072622:INFO:filesystem_verifier_action.cc(237)] Hash of cmnlib64: bKG0zawXzJkSqgiroiRMxPk6IE69QNm92kjyflBosGQ=
04-07 08:35:53.072 1092 1092 I update_engine: [0407/083553.072809:INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (devcfg) on device /dev/block/bootdevice/by-name/devcfg_b
04-07 08:35:53.073 1092 1092 I update_engine: [0407/083553.073858:INFO:filesystem_verifier_action.cc(237)] Hash of devcfg: LFZsp2bTKGS0fCc7Gyeqpdtbg7hApbQvbEh/QB+r0JA=
04-07 08:35:53.073 1092 1092 I update_engine: [0407/083553.073965:INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (hyp) on device /dev/block/bootdevice/by-name/hyp_b
04-07 08:35:53.076 1092 1092 I update_engine: [0407/083553.076447:INFO:filesystem_verifier_action.cc(237)] Hash of hyp: Avo1HXSJFov8V3O9izfiDkuhNm0egVIvrEfiiaRnL8I=
04-07 08:35:53.076 1092 1092 I update_engine: [0407/083553.076654:INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (keymaster) on device /dev/block/bootdevice/by-name/keymaster_b
04-07 08:35:53.078 1092 1092 I update_engine: [0407/083553.078498:INFO:filesystem_verifier_action.cc(237)] Hash of keymaster: Gf2qs1Xd0HfNtVyEiNjfhymp5DA43cNGTL2kcOM3KsY=
04-07 08:35:53.078 1092 1092 I update_engine: [0407/083553.078662:INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (qupfw) on device /dev/block/bootdevice/by-name/qupfw_b
04-07 08:35:53.079 1092 1092 I update_engine: [0407/083553.079701:INFO:filesystem_verifier_action.cc(237)] Hash of qupfw: Mt5UpFvRHdMgbFEBi1fosvy/4IkMlaT+zJGw+H2GTyI=
04-07 08:35:53.079 1092 1092 I update_engine: [0407/083553.079798:INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (tz) on device /dev/block/bootdevice/by-name/tz_b
04-07 08:35:53.089 1092 1092 I update_engine: [0407/083553.089193:INFO:filesystem_verifier_action.cc(237)] Hash of tz: yEmqZAeK9RldOJEL4iM4tO6n5xvmJkIlc3XK/HpB8Ow=
04-07 08:35:53.090 1092 1092 I update_engine: [0407/083553.090042:INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (xbl) on device /dev/block/bootdevice/by-name/xbl_b
04-07 08:35:53.103 1092 1092 I update_engine: [0407/083553.103140:INFO:filesystem_verifier_action.cc(237)] Hash of xbl: LueV+oBfjNfyEH3k6hHO8n4UBfh7lPvnuoCgbrRLT4E=
04-07 08:35:53.104 1092 1092 I update_engine: [0407/083553.104773:INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (xbl_config) on device /dev/block/bootdevice/by-name/xbl_config_b
04-07 08:35:53.106 1092 1092 I update_engine: [0407/083553.106709:INFO:filesystem_verifier_action.cc(237)] Hash of xbl_config: py2pRmpCUcnkjOYDR7ymnNhv1vZ3T6Vux+czI1mBodw=
04-07 08:35:53.106 1092 1092 I update_engine: [0407/083553.106843:INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (modem) on device /dev/block/bootdevice/by-name/modem_b
04-07 08:35:53.366 1092 1092 I update_engine: [0407/083553.366015:INFO:filesystem_verifier_action.cc(237)] Hash of modem: f6AgkXg6Mbv/MOkSq/KdKeoOHVb97D+qmYDx2Ug9i/E=
04-07 08:35:53.401 1092 1092 I update_engine: [0407/083553.401770:INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
04-07 08:35:53.402 1092 1092 I update_engine: [0407/083553.402020:INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
04-07 08:35:53.403 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.000).
04-07 08:35:53.428 1092 1092 I update_engine: [0407/083553.428201:INFO:postinstall_runner_action.cc(172)] Performing postinst (system/bin/otapreopt_script at /postinstall/system/bin/otapreopt_script) installed on device /dev/block/dm-12 and mountable device /dev/block/dm-12
04-07 08:35:53.429 1092 1092 I update_engine: [0407/083553.428419:INFO:postinstall_runner_action.cc(179)] Format file for new system/bin/otapreopt_script is: data
04-07 08:35:53.461 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:36:03.709 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.003).
04-07 08:36:03.735 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:36:06.833 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:36:06.859 2790 11289 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:36:13.670 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.013).
04-07 08:36:13.697 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:36:25.134 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.020).
04-07 08:36:25.162 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:36:36.649 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.030).
04-07 08:36:36.676 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:36:46.392 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.040).
04-07 08:36:46.420 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:36:57.976 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.047).
04-07 08:36:58.015 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:37:06.828 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:37:06.846 2790 12103 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:37:07.832 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.057).
04-07 08:37:07.865 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:37:17.849 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.063).
04-07 08:37:17.887 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:37:29.516 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.073).
04-07 08:37:29.544 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:37:41.007 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.083).
04-07 08:37:41.033 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:37:51.053 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.090).
04-07 08:37:51.082 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:02.591 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.100).
04-07 08:38:02.630 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:06.841 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:38:06.854 2790 12900 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:38:14.255 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.110).
04-07 08:38:14.288 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:14.510 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:38:14.526 2790 13057 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:38:25.831 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.120).
04-07 08:38:25.854 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:28.174 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:38:28.196 2790 13208 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:38:35.894 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.127).
04-07 08:38:35.927 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:46.600 2790 3849 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.133).
04-07 08:38:46.664 2790 3849 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:56.427 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.143).
04-07 08:38:56.462 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:38:58.200 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:38:58.216 2790 13627 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:06.845 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:39:06.872 2790 13759 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:08.054 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.153).
04-07 08:39:08.088 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:39:08.543 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:39:08.556 2790 13759 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:10.135 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:39:10.152 2790 13759 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:10.800 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:39:10.813 2790 13759 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:12.082 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:39:12.098 2790 13759 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:13.273 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:39:13.288 2790 13759 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:39:19.606 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.160).
04-07 08:39:19.636 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:39:29.610 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.170).
04-07 08:39:29.650 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:39:41.055 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.180).
04-07 08:39:41.098 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:39:48.286 2790 14318 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:39:48.306 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Status change: status=0x211, isSetupWizard=false.
04-07 08:39:51.091 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.187).
04-07 08:39:51.104 7462 7462 I SystemUpdate: [Phone,SystemUpdateChimeraActivity] Progress update: progress=0.186667.
04-07 08:39:51.119 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:39:53.452 2790 14323 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 110.
04-07 08:40:02.667 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.197).
04-07 08:40:02.687 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:40:06.829 2790 3790 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:40:06.839 2790 14537 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:40:14.329 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.207).
04-07 08:40:14.356 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:40:24.388 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.213).
04-07 08:40:24.430 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:40:35.911 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.223).
04-07 08:40:35.942 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:40:47.510 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.233).
04-07 08:40:47.554 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:40:57.529 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.240).
04-07 08:40:57.567 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:41:06.847 2790 3790 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:41:06.865 2790 15377 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:41:09.168 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.250).
04-07 08:41:09.199 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:41:20.606 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.260).
04-07 08:41:20.638 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:41:30.626 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.267).
04-07 08:41:30.653 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:41:40.676 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.273).
04-07 08:41:40.718 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:41:50.491 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.283).
04-07 08:41:50.518 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:42:00.709 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.290).
04-07 08:42:00.736 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:42:06.801 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:42:06.808 2790 16328 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:42:10.566 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.300).
04-07 08:42:10.600 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:42:20.732 2790 4245 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.307).
04-07 08:42:20.758 2790 4245 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:42:32.277 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.317).
04-07 08:42:32.304 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:42:43.822 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.327).
04-07 08:42:43.855 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:42:53.754 1092 1092 I update_engine: [0407/084253.754526:INFO:subprocess.cc(157)] Subprocess output:
04-07 08:42:53.754 1092 1092 I update_engine: Complete or error.
04-07 08:42:53.754 1092 1092 I update_engine:
04-07 08:42:53.781 1092 1092 I update_engine: [0407/084253.781289:INFO:postinstall_runner_action.cc(172)] Performing postinst (bin/check_dynamic_partitions at /postinstall/bin/check_dynamic_partitions) installed on device /dev/block/dm-13 and mountable device /dev/block/dm-13
04-07 08:42:53.783 1092 1092 I update_engine: [0407/084253.781596:INFO:postinstall_runner_action.cc(179)] Format file for new bin/check_dynamic_partitions is: data
04-07 08:42:53.858 2790 10908 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.667).
04-07 08:42:53.861 1092 1092 I update_engine: [0407/084253.861265:INFO:postinstall_runner_action.cc(172)] Performing postinst (bin/checkpoint_gc at /postinstall/bin/checkpoint_gc) installed on device /dev/block/dm-14 and mountable device /dev/block/dm-14
04-07 08:42:53.862 1092 1092 I update_engine: [0407/084253.861594:INFO:postinstall_runner_action.cc(179)] Format file for new bin/checkpoint_gc is: data
04-07 08:42:53.885 2790 10908 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:04.151 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.682).
04-07 08:43:04.179 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:06.806 2790 2926 I SystemUpdate: [Control,BatteryControl] Received intent: Intent { act=android.intent.action.BATTERY_CHANGED flg=0x60000010 (has extras) }.
04-07 08:43:06.816 2790 17058 I SystemUpdate: [Execution,InstallationEventIntentOperation] Handling event of type 4.
04-07 08:43:09.224 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.725).
04-07 08:43:09.268 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:14.327 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.825).
04-07 08:43:14.400 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:19.410 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 0.994).
04-07 08:43:19.449 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:24.545 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x211, 1.000).
04-07 08:43:24.546 1092 1092 I update_engine: [0407/084324.546615:INFO:postinstall_runner_action.cc(364)] All post-install commands succeeded
04-07 08:43:24.547 1092 1092 I update_engine: [0407/084324.547593:INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kUpdatedButNotActive
04-07 08:43:24.547 1092 1092 I update_engine: [0407/084324.547894:INFO:update_attempter_android.cc(454)] Processing Done.
04-07 08:43:24.548 1092 1092 I update_engine: [0407/084324.547979:INFO:dynamic_partition_control_android.cc(151)] Destroying [product_b, system_b, vendor_b] from device mapper
04-07 08:43:24.576 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:24.577 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x005.
04-07 08:43:24.773 1092 1092 I update_engine: [0407/084324.773403:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped product_b from device mapper.
04-07 08:43:25.197 1092 1092 I update_engine: [0407/084325.197531:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped system_b from device mapper.
04-07 08:43:25.620 1092 1092 I update_engine: [0407/084325.620465:INFO:dynamic_partition_control_android.cc(140)] Successfully unmapped vendor_b from device mapper.
04-07 08:43:25.638 2790 3393 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x111, 0.000).
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644341:INFO:metrics_reporter_android.cc(29)] uploading 1 to histogram for metric ota_update_engine_attempt_number
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644488:INFO:metrics_reporter_android.cc(29)] uploading 0 to histogram for metric ota_update_engine_attempt_payload_type
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644546:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_attempt_fixed_duration_boottime_in_minutes
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644599:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_attempt_duration_monotonic_in_minutes
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644651:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_attempt_payload_size_mib
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644704:INFO:metrics_reporter_android.cc(29)] uploading 12 to histogram for metric ota_update_engine_attempt_result
04-07 08:43:25.644 1092 1092 I update_engine: [0407/084325.644778:INFO:metrics_reporter_android.cc(29)] uploading 52 to histogram for metric ota_update_engine_attempt_error_code
04-07 08:43:25.646 1092 1092 I update_engine: [0407/084325.646923:INFO:metrics_reporter_android.cc(29)] uploading 11 to histogram for metric ota_update_engine_attempt_fixed_current_bytes_downloaded_mib
04-07 08:43:25.677 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
04-07 08:43:25.696 2790 3393 I SystemUpdate: [Control,InstallationControl] Update engine status updated to 0x000.
04-07 08:43:25.732 2790 10961 I SystemUpdate: [Execution,SynchronizedUpdateEngine] stop()
04-07 08:43:25.732 2790 10961 I SystemUpdate: [Execution,ExecutionManager] Action streaming-apply executed for 709.90 seconds.
04-07 08:43:25.743 2790 10961 I SystemUpdate: [Control,InstallationControl] Installation progress updated to (0x110, -1.000).
04-07 08:43:25.896 2790 10961 I SystemUpdate: [Execution,ExecutionManager] Action ab-reboot executed for 0.16 seconds.
04-07 08:45:49.264 1092 1092 I update_engine: [0407/084549.264120:INFO:main.cc(198)] A/B Update Engine terminating with exit code 0
04-07 08:45:49.264 1092 1092 I update_engine: [0407/084549.264380:INFO:dynamic_partition_control_android.cc(151)] Destroying [] from device mapper
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment